builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-012 starttime: 1471900489.55 results: success (0) buildid: 20160822111416 builduid: 3922994bc8444012abb7d2a159a4ed99 revision: 2a0981531355dc77614e72c72fda7498bb074ffe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.547961) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.548491) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.548777) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.600442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.600748) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 14:14:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-08-22 14:14:49 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086996 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.701487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.701795) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015121 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.750958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:49.751320) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2a0981531355dc77614e72c72fda7498bb074ffe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2a0981531355dc77614e72c72fda7498bb074ffe --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 14:14:49,975 truncating revision to first 12 chars 2016-08-22 14:14:49,975 Setting DEBUG logging. 2016-08-22 14:14:49,975 attempt 1/10 2016-08-22 14:14:49,975 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2a0981531355?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 14:14:50,310 unpacking tar archive at: mozilla-aurora-2a0981531355/testing/mozharness/ program finished with exit code 0 elapsedTime=0.646520 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:50.412765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:50.413293) ========= script_repo_revision: 2a0981531355dc77614e72c72fda7498bb074ffe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:50.413731) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:50.414209) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 14:14:50.424693) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 14:14:50.425133) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:14:50 INFO - MultiFileLogger online at 20160822 14:14:50 in /builds/slave/test 14:14:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:14:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:14:50 INFO - {'append_to_log': False, 14:14:50 INFO - 'base_work_dir': '/builds/slave/test', 14:14:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:14:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:14:50 INFO - 'branch': 'Mozilla-Aurora', 14:14:50 INFO - 'buildbot_json_path': 'buildprops.json', 14:14:50 INFO - 'config_files': ('talos/linux_config.py',), 14:14:50 INFO - 'default_actions': ('clobber', 14:14:50 INFO - 'read-buildbot-config', 14:14:50 INFO - 'download-and-extract', 14:14:50 INFO - 'populate-webroot', 14:14:50 INFO - 'create-virtualenv', 14:14:50 INFO - 'install', 14:14:50 INFO - 'run-tests'), 14:14:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:14:50 INFO - 'download_minidump_stackwalk': True, 14:14:50 INFO - 'download_symbols': 'ondemand', 14:14:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:14:50 INFO - 'tooltool.py': '/tools/tooltool.py', 14:14:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:14:50 INFO - '/tools/misc-python/virtualenv.py')}, 14:14:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:14:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:14:50 INFO - 'installer_path': 'installer.exe', 14:14:50 INFO - 'log_level': 'info', 14:14:50 INFO - 'log_name': 'talos', 14:14:50 INFO - 'log_to_console': True, 14:14:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:14:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:14:50 INFO - 'opt_config_files': (), 14:14:50 INFO - 'pip_index': False, 14:14:50 INFO - 'sps_profile': False, 14:14:50 INFO - 'sps_profile_interval': 0, 14:14:50 INFO - 'suite': 'dromaeojs-e10s', 14:14:50 INFO - 'system_bits': '32', 14:14:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:14:50 INFO - 'title': 'talos-linux64-ix-012', 14:14:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:14:50 INFO - 'use_talos_json': True, 14:14:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:14:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:14:50 INFO - 'work_dir': 'build'} 14:14:50 INFO - [mozharness: 2016-08-22 21:14:50.736895Z] Running clobber step. 14:14:50 INFO - Running pre-action listener: _resource_record_pre_action 14:14:50 INFO - Running main action method: clobber 14:14:50 INFO - rmtree: /builds/slave/test/build 14:14:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - [mozharness: 2016-08-22 21:14:51.705199Z] Finished clobber step (success) 14:14:51 INFO - [mozharness: 2016-08-22 21:14:51.705299Z] Running read-buildbot-config step. 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: read_buildbot_config 14:14:51 INFO - Using buildbot properties: 14:14:51 INFO - { 14:14:51 INFO - "project": "", 14:14:51 INFO - "product": "firefox", 14:14:51 INFO - "script_repo_revision": "production", 14:14:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 14:14:51 INFO - "repository": "", 14:14:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 14:14:51 INFO - "buildid": "20160822111416", 14:14:51 INFO - "pgo_build": "True", 14:14:51 INFO - "basedir": "/builds/slave/test", 14:14:51 INFO - "buildnumber": 499, 14:14:51 INFO - "slavename": "talos-linux64-ix-012", 14:14:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:14:51 INFO - "platform": "ubuntu64_hw", 14:14:51 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:14:51 INFO - "branch": "mozilla-aurora", 14:14:51 INFO - "repo_path": "releases/mozilla-aurora", 14:14:51 INFO - "revision": "2a0981531355dc77614e72c72fda7498bb074ffe", 14:14:51 INFO - "stage_platform": "linux64-pgo", 14:14:51 INFO - "builduid": "3922994bc8444012abb7d2a159a4ed99", 14:14:51 INFO - "slavebuilddir": "test" 14:14:51 INFO - } 14:14:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - [mozharness: 2016-08-22 21:14:51.708097Z] Finished read-buildbot-config step (success) 14:14:51 INFO - [mozharness: 2016-08-22 21:14:51.708186Z] Running download-and-extract step. 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: download_and_extract 14:14:51 INFO - mkdir: /builds/slave/test/build/tests 14:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:14:51 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:14:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:14:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:14:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:14:51 INFO - retry: Failed, sleeping 30 seconds before retrying 14:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 14:15:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:15:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:15:21 INFO - retry: Failed, sleeping 60 seconds before retrying 14:16:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 14:16:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:16:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 14:16:21 INFO - Caught exception: HTTP Error 404: Not Found 14:16:21 INFO - Caught exception: HTTP Error 404: Not Found 14:16:21 INFO - Caught exception: HTTP Error 404: Not Found 14:16:21 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:21 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:16:22 INFO - Downloaded 1361 bytes. 14:16:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:16:22 INFO - Using the following test package requirements: 14:16:22 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:16:22 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:16:22 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:16:22 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'jsshell-linux-x86_64.zip'], 14:16:22 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:16:22 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:16:22 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:16:22 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:16:22 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:16:22 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:16:22 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:16:22 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:16:22 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:16:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:16:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:16:22 INFO - retry: Failed, sleeping 30 seconds before retrying 14:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:16:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:16:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:16:52 INFO - retry: Failed, sleeping 60 seconds before retrying 14:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:17:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:17:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:17:52 INFO - Caught exception: HTTP Error 404: Not Found 14:17:52 INFO - Caught exception: HTTP Error 404: Not Found 14:17:52 INFO - Caught exception: HTTP Error 404: Not Found 14:17:52 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:52 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:17:53 INFO - Downloaded 21767213 bytes. 14:17:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:17:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:17:54 INFO - Return code: 0 14:17:54 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:54 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:17:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:17:54 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:17:54 INFO - retry: Failed, sleeping 30 seconds before retrying 14:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:18:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:18:24 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:18:24 INFO - retry: Failed, sleeping 60 seconds before retrying 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:19:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:24 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:19:24 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:19:24 INFO - Caught exception: HTTP Error 404: Not Found 14:19:24 INFO - Caught exception: HTTP Error 404: Not Found 14:19:24 INFO - Caught exception: HTTP Error 404: Not Found 14:19:24 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:19:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:19:25 INFO - Downloaded 21767213 bytes. 14:19:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:19:26 INFO - Return code: 0 14:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:26 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:19:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:19:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:19:26 INFO - retry: Failed, sleeping 30 seconds before retrying 14:19:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:19:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:19:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:19:56 INFO - retry: Failed, sleeping 60 seconds before retrying 14:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:20:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:20:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:20:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:20:56 INFO - Caught exception: HTTP Error 404: Not Found 14:20:56 INFO - Caught exception: HTTP Error 404: Not Found 14:20:56 INFO - Caught exception: HTTP Error 404: Not Found 14:20:56 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:20:56 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:20:58 INFO - Downloaded 14413723 bytes. 14:20:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:20:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:20:58 INFO - Return code: 0 14:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:20:58 INFO - https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:20:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:20:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:20:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:20:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:20:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:20:58 INFO - retry: Failed, sleeping 30 seconds before retrying 14:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:21:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:21:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:21:28 INFO - retry: Failed, sleeping 60 seconds before retrying 14:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:22:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:22:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:22:28 INFO - Caught exception: HTTP Error 404: Not Found 14:22:28 INFO - Caught exception: HTTP Error 404: Not Found 14:22:28 INFO - Caught exception: HTTP Error 404: Not Found 14:22:28 INFO - trying https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:28 INFO - Downloading https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:22:30 INFO - Downloaded 63764314 bytes. 14:22:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:30 INFO - mkdir: /builds/slave/test/properties 14:22:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:30 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:30 INFO - Contents: 14:22:30 INFO - build_url:https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:22:30 INFO - Running post-action listener: _resource_record_post_action 14:22:30 INFO - Running post-action listener: set_extra_try_arguments 14:22:30 INFO - [mozharness: 2016-08-22 21:22:30.796342Z] Finished download-and-extract step (success) 14:22:30 INFO - [mozharness: 2016-08-22 21:22:30.796576Z] Running populate-webroot step. 14:22:30 INFO - Running pre-action listener: _resource_record_pre_action 14:22:30 INFO - Running main action method: populate_webroot 14:22:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:30 INFO - '--tppagecycles', 14:22:30 INFO - '7'], 14:22:30 INFO - 'tests': ['tsvgm']}}, 14:22:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:22:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:22:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:22:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:22:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['damp', 'tps']}, 14:22:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'tests': ['damp', 'tps']}, 14:22:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['dromaeo_dom']}, 14:22:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['basic_compositor_video']}, 14:22:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:22:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'other-e10s': {'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['a11yr', 14:22:30 INFO - 'ts_paint', 14:22:30 INFO - 'tpaint', 14:22:30 INFO - 'sessionrestore', 14:22:30 INFO - 'sessionrestore_no_auto_restore', 14:22:30 INFO - 'tabpaint']}, 14:22:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['tsvgx', 14:22:30 INFO - 'tsvgr_opacity', 14:22:30 INFO - 'tart', 14:22:30 INFO - 'tscrollx', 14:22:30 INFO - 'cart']}, 14:22:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:22:30 INFO - 'tsvgr_opacity', 14:22:30 INFO - 'tart', 14:22:30 INFO - 'tscrollx', 14:22:30 INFO - 'cart']}, 14:22:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'talos_options': ['--disable-e10s'], 14:22:30 INFO - 'tests': ['tp5o']}, 14:22:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'tests': ['tp5o']}, 14:22:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'talos_options': ['--disable-e10s', 14:22:30 INFO - '--xperf_path', 14:22:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:30 INFO - 'tests': ['tp5n']}, 14:22:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:30 INFO - 'talos_options': ['--xperf_path', 14:22:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:30 INFO - 'tests': ['tp5n']}}, 14:22:30 INFO - 'talos.zip': {'path': '', 14:22:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:30 INFO - Running post-action listener: _resource_record_post_action 14:22:30 INFO - [mozharness: 2016-08-22 21:22:30.815634Z] Finished populate-webroot step (success) 14:22:30 INFO - [mozharness: 2016-08-22 21:22:30.815724Z] Running create-virtualenv step. 14:22:30 INFO - Running pre-action listener: _resource_record_pre_action 14:22:30 INFO - Running main action method: create_virtualenv 14:22:30 INFO - Creating virtualenv /builds/slave/test/build/venv 14:22:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:22:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:22:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:30 INFO - Using real prefix '/usr' 14:22:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:22:31 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:33 INFO - Installing pip.................done. 14:22:33 INFO - Return code: 0 14:22:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:22:33 INFO - Using env: {'DISPLAY': ':0', 14:22:33 INFO - 'HOME': '/home/cltbld', 14:22:33 INFO - 'LANG': 'en_US.UTF-8', 14:22:33 INFO - 'LANGUAGE': 'en_US:en', 14:22:33 INFO - 'LOGNAME': 'cltbld', 14:22:33 INFO - 'MAIL': '/var/mail/cltbld', 14:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:33 INFO - 'MOZ_NO_REMOTE': '1', 14:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:33 INFO - 'NO_EM_RESTART': '1', 14:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:33 INFO - 'PWD': '/builds/slave/test', 14:22:33 INFO - 'SHELL': '/bin/bash', 14:22:33 INFO - 'SHLVL': '1', 14:22:33 INFO - 'TERM': 'linux', 14:22:33 INFO - 'TMOUT': '86400', 14:22:33 INFO - 'USER': 'cltbld', 14:22:33 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:33 INFO - '_': '/tools/buildbot/bin/python'} 14:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:22:33 INFO - Cleaning up... 14:22:33 INFO - Return code: 0 14:22:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:22:33 INFO - Using env: {'DISPLAY': ':0', 14:22:33 INFO - 'HOME': '/home/cltbld', 14:22:33 INFO - 'LANG': 'en_US.UTF-8', 14:22:33 INFO - 'LANGUAGE': 'en_US:en', 14:22:33 INFO - 'LOGNAME': 'cltbld', 14:22:33 INFO - 'MAIL': '/var/mail/cltbld', 14:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:33 INFO - 'MOZ_NO_REMOTE': '1', 14:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:33 INFO - 'NO_EM_RESTART': '1', 14:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:33 INFO - 'PWD': '/builds/slave/test', 14:22:33 INFO - 'SHELL': '/bin/bash', 14:22:33 INFO - 'SHLVL': '1', 14:22:33 INFO - 'TERM': 'linux', 14:22:33 INFO - 'TMOUT': '86400', 14:22:33 INFO - 'USER': 'cltbld', 14:22:33 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:33 INFO - '_': '/tools/buildbot/bin/python'} 14:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:33 INFO - Downloading/unpacking psutil>=3.1.1 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:35 INFO - Installing collected packages: psutil 14:22:35 INFO - Running setup.py install for psutil 14:22:35 INFO - building 'psutil._psutil_linux' extension 14:22:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:22:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:22:36 INFO - building 'psutil._psutil_posix' extension 14:22:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:22:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:22:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:36 INFO - Successfully installed psutil 14:22:36 INFO - Cleaning up... 14:22:36 INFO - Return code: 0 14:22:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:22:36 INFO - Using env: {'DISPLAY': ':0', 14:22:36 INFO - 'HOME': '/home/cltbld', 14:22:36 INFO - 'LANG': 'en_US.UTF-8', 14:22:36 INFO - 'LANGUAGE': 'en_US:en', 14:22:36 INFO - 'LOGNAME': 'cltbld', 14:22:36 INFO - 'MAIL': '/var/mail/cltbld', 14:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:36 INFO - 'MOZ_NO_REMOTE': '1', 14:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:36 INFO - 'NO_EM_RESTART': '1', 14:22:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:36 INFO - 'PWD': '/builds/slave/test', 14:22:36 INFO - 'SHELL': '/bin/bash', 14:22:36 INFO - 'SHLVL': '1', 14:22:36 INFO - 'TERM': 'linux', 14:22:36 INFO - 'TMOUT': '86400', 14:22:36 INFO - 'USER': 'cltbld', 14:22:36 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:36 INFO - '_': '/tools/buildbot/bin/python'} 14:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:22:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:22:39 INFO - Installing collected packages: mozsystemmonitor 14:22:39 INFO - Running setup.py install for mozsystemmonitor 14:22:39 INFO - Successfully installed mozsystemmonitor 14:22:39 INFO - Cleaning up... 14:22:39 INFO - Return code: 0 14:22:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:22:39 INFO - Using env: {'DISPLAY': ':0', 14:22:39 INFO - 'HOME': '/home/cltbld', 14:22:39 INFO - 'LANG': 'en_US.UTF-8', 14:22:39 INFO - 'LANGUAGE': 'en_US:en', 14:22:39 INFO - 'LOGNAME': 'cltbld', 14:22:39 INFO - 'MAIL': '/var/mail/cltbld', 14:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:39 INFO - 'MOZ_NO_REMOTE': '1', 14:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:39 INFO - 'NO_EM_RESTART': '1', 14:22:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:39 INFO - 'PWD': '/builds/slave/test', 14:22:39 INFO - 'SHELL': '/bin/bash', 14:22:39 INFO - 'SHLVL': '1', 14:22:39 INFO - 'TERM': 'linux', 14:22:39 INFO - 'TMOUT': '86400', 14:22:39 INFO - 'USER': 'cltbld', 14:22:39 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:39 INFO - '_': '/tools/buildbot/bin/python'} 14:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:39 INFO - Downloading/unpacking jsonschema==2.5.1 14:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:41 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:42 INFO - warning: no files found matching '*.txt' 14:22:42 INFO - no previously-included directories found matching 'build' 14:22:42 INFO - no previously-included directories found matching 'dist' 14:22:42 INFO - no previously-included directories found matching '.git*' 14:22:42 INFO - Installing collected packages: jsonschema, functools32 14:22:42 INFO - Running setup.py install for functools32 14:22:42 INFO - warning: no files found matching '*.txt' 14:22:42 INFO - no previously-included directories found matching 'build' 14:22:42 INFO - no previously-included directories found matching 'dist' 14:22:42 INFO - no previously-included directories found matching '.git*' 14:22:42 INFO - Successfully installed jsonschema functools32 14:22:42 INFO - Cleaning up... 14:22:42 INFO - Return code: 0 14:22:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:22:42 INFO - Using env: {'DISPLAY': ':0', 14:22:42 INFO - 'HOME': '/home/cltbld', 14:22:42 INFO - 'LANG': 'en_US.UTF-8', 14:22:42 INFO - 'LANGUAGE': 'en_US:en', 14:22:42 INFO - 'LOGNAME': 'cltbld', 14:22:42 INFO - 'MAIL': '/var/mail/cltbld', 14:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:42 INFO - 'MOZ_NO_REMOTE': '1', 14:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:42 INFO - 'NO_EM_RESTART': '1', 14:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:42 INFO - 'PWD': '/builds/slave/test', 14:22:42 INFO - 'SHELL': '/bin/bash', 14:22:42 INFO - 'SHLVL': '1', 14:22:42 INFO - 'TERM': 'linux', 14:22:42 INFO - 'TMOUT': '86400', 14:22:42 INFO - 'USER': 'cltbld', 14:22:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:42 INFO - '_': '/tools/buildbot/bin/python'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:22:42 INFO - Cleaning up... 14:22:42 INFO - Return code: 0 14:22:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:22:42 INFO - Using env: {'DISPLAY': ':0', 14:22:42 INFO - 'HOME': '/home/cltbld', 14:22:42 INFO - 'LANG': 'en_US.UTF-8', 14:22:42 INFO - 'LANGUAGE': 'en_US:en', 14:22:42 INFO - 'LOGNAME': 'cltbld', 14:22:42 INFO - 'MAIL': '/var/mail/cltbld', 14:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:42 INFO - 'MOZ_NO_REMOTE': '1', 14:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:42 INFO - 'NO_EM_RESTART': '1', 14:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:42 INFO - 'PWD': '/builds/slave/test', 14:22:42 INFO - 'SHELL': '/bin/bash', 14:22:42 INFO - 'SHLVL': '1', 14:22:42 INFO - 'TERM': 'linux', 14:22:42 INFO - 'TMOUT': '86400', 14:22:42 INFO - 'USER': 'cltbld', 14:22:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:42 INFO - '_': '/tools/buildbot/bin/python'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 INFO - Downloading/unpacking blobuploader==1.2.4 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:22:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:45 INFO - Downloading docopt-0.6.1.tar.gz 14:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:45 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:45 INFO - Running setup.py install for blobuploader 14:22:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:45 INFO - Running setup.py install for requests 14:22:45 INFO - Running setup.py install for docopt 14:22:45 INFO - Successfully installed blobuploader requests docopt 14:22:45 INFO - Cleaning up... 14:22:45 INFO - Return code: 0 14:22:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:45 INFO - Using env: {'DISPLAY': ':0', 14:22:45 INFO - 'HOME': '/home/cltbld', 14:22:45 INFO - 'LANG': 'en_US.UTF-8', 14:22:45 INFO - 'LANGUAGE': 'en_US:en', 14:22:45 INFO - 'LOGNAME': 'cltbld', 14:22:45 INFO - 'MAIL': '/var/mail/cltbld', 14:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:45 INFO - 'MOZ_NO_REMOTE': '1', 14:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:45 INFO - 'NO_EM_RESTART': '1', 14:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:45 INFO - 'PWD': '/builds/slave/test', 14:22:45 INFO - 'SHELL': '/bin/bash', 14:22:45 INFO - 'SHLVL': '1', 14:22:45 INFO - 'TERM': 'linux', 14:22:45 INFO - 'TMOUT': '86400', 14:22:45 INFO - 'USER': 'cltbld', 14:22:45 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:45 INFO - '_': '/tools/buildbot/bin/python'} 14:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:46 INFO - Running setup.py (path:/tmp/pip-ecABTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:46 INFO - Running setup.py (path:/tmp/pip-Ey3yQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:46 INFO - Running setup.py (path:/tmp/pip-Q4oTsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:46 INFO - Running setup.py (path:/tmp/pip-t9uqYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:46 INFO - Running setup.py (path:/tmp/pip-hvOpcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:46 INFO - Running setup.py (path:/tmp/pip-yjqwei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:46 INFO - Running setup.py (path:/tmp/pip-St48CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:46 INFO - Running setup.py (path:/tmp/pip-b0xYua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:46 INFO - Running setup.py (path:/tmp/pip-LgC05s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:46 INFO - Running setup.py (path:/tmp/pip-SrPMaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:46 INFO - Running setup.py (path:/tmp/pip-Sq1NBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:46 INFO - Running setup.py (path:/tmp/pip-zwa7Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:46 INFO - Running setup.py (path:/tmp/pip-8hp1kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:47 INFO - Running setup.py (path:/tmp/pip-au0QJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:47 INFO - Running setup.py (path:/tmp/pip-FTh7Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:47 INFO - Running setup.py (path:/tmp/pip-96BeGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:47 INFO - Running setup.py (path:/tmp/pip-QCYN9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:47 INFO - Running setup.py install for manifestparser 14:22:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:22:47 INFO - Running setup.py install for mozcrash 14:22:47 INFO - Running setup.py install for mozdebug 14:22:47 INFO - Running setup.py install for mozdevice 14:22:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:22:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:22:47 INFO - Running setup.py install for mozfile 14:22:47 INFO - Running setup.py install for mozhttpd 14:22:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:22:47 INFO - Running setup.py install for mozinfo 14:22:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for mozInstall 14:22:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for mozleak 14:22:48 INFO - Running setup.py install for mozlog 14:22:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for moznetwork 14:22:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for mozprocess 14:22:48 INFO - Running setup.py install for mozprofile 14:22:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for mozrunner 14:22:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:22:48 INFO - Running setup.py install for mozscreenshot 14:22:48 INFO - Running setup.py install for moztest 14:22:49 INFO - Running setup.py install for mozversion 14:22:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:22:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:49 INFO - Cleaning up... 14:22:49 INFO - Return code: 0 14:22:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:49 INFO - Using env: {'DISPLAY': ':0', 14:22:49 INFO - 'HOME': '/home/cltbld', 14:22:49 INFO - 'LANG': 'en_US.UTF-8', 14:22:49 INFO - 'LANGUAGE': 'en_US:en', 14:22:49 INFO - 'LOGNAME': 'cltbld', 14:22:49 INFO - 'MAIL': '/var/mail/cltbld', 14:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:49 INFO - 'MOZ_NO_REMOTE': '1', 14:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:49 INFO - 'NO_EM_RESTART': '1', 14:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:49 INFO - 'PWD': '/builds/slave/test', 14:22:49 INFO - 'SHELL': '/bin/bash', 14:22:49 INFO - 'SHLVL': '1', 14:22:49 INFO - 'TERM': 'linux', 14:22:49 INFO - 'TMOUT': '86400', 14:22:49 INFO - 'USER': 'cltbld', 14:22:49 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:49 INFO - '_': '/tools/buildbot/bin/python'} 14:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:49 INFO - Running setup.py (path:/tmp/pip-hJZq8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:49 INFO - Running setup.py (path:/tmp/pip-6uhBZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:49 INFO - Running setup.py (path:/tmp/pip-tajrpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:49 INFO - Running setup.py (path:/tmp/pip-4NDJJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:49 INFO - Running setup.py (path:/tmp/pip-3byz2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:49 INFO - Running setup.py (path:/tmp/pip-Qhlf8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:49 INFO - Running setup.py (path:/tmp/pip-uQBLTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:49 INFO - Running setup.py (path:/tmp/pip-_kc9bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:49 INFO - Running setup.py (path:/tmp/pip-90XYM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:50 INFO - Running setup.py (path:/tmp/pip-3gTtOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:50 INFO - Running setup.py (path:/tmp/pip-E65kXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:50 INFO - Running setup.py (path:/tmp/pip-ePTW0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:50 INFO - Running setup.py (path:/tmp/pip-Mapv9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:50 INFO - Running setup.py (path:/tmp/pip-PikmQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:50 INFO - Running setup.py (path:/tmp/pip-0Meqo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:50 INFO - Running setup.py (path:/tmp/pip-9qlFvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:50 INFO - Running setup.py (path:/tmp/pip-OFePkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:22:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:52 INFO - Downloading blessings-1.6.tar.gz 14:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:52 INFO - Installing collected packages: blessings 14:22:52 INFO - Running setup.py install for blessings 14:22:52 INFO - Successfully installed blessings 14:22:52 INFO - Cleaning up... 14:22:52 INFO - Return code: 0 14:22:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:22:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:52 INFO - Reading from file tmpfile_stdout 14:22:52 INFO - Current package versions: 14:22:52 INFO - argparse == 1.2.1 14:22:52 INFO - blessings == 1.6 14:22:52 INFO - blobuploader == 1.2.4 14:22:52 INFO - docopt == 0.6.1 14:22:52 INFO - functools32 == 3.2.3-2 14:22:52 INFO - jsonschema == 2.5.1 14:22:52 INFO - manifestparser == 1.1 14:22:52 INFO - mozInstall == 1.12 14:22:52 INFO - mozcrash == 0.17 14:22:52 INFO - mozdebug == 0.1 14:22:52 INFO - mozdevice == 0.48 14:22:52 INFO - mozfile == 1.2 14:22:52 INFO - mozhttpd == 0.7 14:22:52 INFO - mozinfo == 0.9 14:22:52 INFO - mozleak == 0.1 14:22:52 INFO - mozlog == 3.3 14:22:52 INFO - moznetwork == 0.27 14:22:52 INFO - mozprocess == 0.23 14:22:52 INFO - mozprofile == 0.28 14:22:52 INFO - mozrunner == 6.12 14:22:52 INFO - mozscreenshot == 0.1 14:22:52 INFO - mozsystemmonitor == 0.3 14:22:52 INFO - moztest == 0.7 14:22:52 INFO - mozversion == 1.4 14:22:52 INFO - psutil == 3.1.1 14:22:52 INFO - requests == 1.2.3 14:22:52 INFO - wsgiref == 0.1.2 14:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:22:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:52 INFO - Using env: {'DISPLAY': ':0', 14:22:52 INFO - 'HOME': '/home/cltbld', 14:22:52 INFO - 'LANG': 'en_US.UTF-8', 14:22:52 INFO - 'LANGUAGE': 'en_US:en', 14:22:52 INFO - 'LOGNAME': 'cltbld', 14:22:52 INFO - 'MAIL': '/var/mail/cltbld', 14:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:52 INFO - 'MOZ_NO_REMOTE': '1', 14:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:52 INFO - 'NO_EM_RESTART': '1', 14:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:52 INFO - 'PWD': '/builds/slave/test', 14:22:52 INFO - 'SHELL': '/bin/bash', 14:22:52 INFO - 'SHLVL': '1', 14:22:52 INFO - 'TERM': 'linux', 14:22:52 INFO - 'TMOUT': '86400', 14:22:52 INFO - 'USER': 'cltbld', 14:22:52 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:52 INFO - '_': '/tools/buildbot/bin/python'} 14:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:22:53 INFO - Cleaning up... 14:22:53 INFO - Return code: 0 14:22:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a098a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ad5400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:22:53 INFO - Using env: {'DISPLAY': ':0', 14:22:53 INFO - 'HOME': '/home/cltbld', 14:22:53 INFO - 'LANG': 'en_US.UTF-8', 14:22:53 INFO - 'LANGUAGE': 'en_US:en', 14:22:53 INFO - 'LOGNAME': 'cltbld', 14:22:53 INFO - 'MAIL': '/var/mail/cltbld', 14:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:53 INFO - 'MOZ_NO_REMOTE': '1', 14:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:22:53 INFO - 'NO_EM_RESTART': '1', 14:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:53 INFO - 'PWD': '/builds/slave/test', 14:22:53 INFO - 'SHELL': '/bin/bash', 14:22:53 INFO - 'SHLVL': '1', 14:22:53 INFO - 'TERM': 'linux', 14:22:53 INFO - 'TMOUT': '86400', 14:22:53 INFO - 'USER': 'cltbld', 14:22:53 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:53 INFO - '_': '/tools/buildbot/bin/python'} 14:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:22:53 INFO - Cleaning up... 14:22:53 INFO - Return code: 0 14:22:53 INFO - Running post-action listener: _resource_record_post_action 14:22:53 INFO - Running post-action listener: _start_resource_monitoring 14:22:53 INFO - Starting resource monitoring. 14:22:53 INFO - [mozharness: 2016-08-22 21:22:53.420922Z] Finished create-virtualenv step (success) 14:22:53 INFO - [mozharness: 2016-08-22 21:22:53.421353Z] Running install step. 14:22:53 INFO - Running pre-action listener: _resource_record_pre_action 14:22:53 INFO - Running main action method: install 14:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:22:53 INFO - Reading from file tmpfile_stdout 14:22:53 INFO - Detecting whether we're running mozinstall >=1.0... 14:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:22:53 INFO - Reading from file tmpfile_stdout 14:22:53 INFO - Output received: 14:22:53 INFO - Usage: mozinstall [options] installer 14:22:53 INFO - Options: 14:22:53 INFO - -h, --help show this help message and exit 14:22:53 INFO - -d DEST, --destination=DEST 14:22:53 INFO - Directory to install application into. [default: 14:22:53 INFO - "/builds/slave/test"] 14:22:53 INFO - --app=APP Application being installed. [default: firefox] 14:22:53 INFO - mkdir: /builds/slave/test/build/application 14:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:23:09 INFO - Reading from file tmpfile_stdout 14:23:09 INFO - Output received: 14:23:09 INFO - /builds/slave/test/build/application/firefox/firefox 14:23:09 INFO - Running post-action listener: _resource_record_post_action 14:23:09 INFO - [mozharness: 2016-08-22 21:23:09.708850Z] Finished install step (success) 14:23:09 INFO - [mozharness: 2016-08-22 21:23:09.709127Z] Running run-tests step. 14:23:09 INFO - Running pre-action listener: _resource_record_pre_action 14:23:09 INFO - Running main action method: run_tests 14:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:23:09 INFO - Python 2.7.3 14:23:09 INFO - Return code: 0 14:23:09 INFO - grabbing minidump binary from tooltool 14:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b64570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b65470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b65900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:23:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:23:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:23:09 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:23:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:09 INFO - Return code: 0 14:23:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:23:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:23:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:23:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:23:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:23:09 INFO - Using env: {'DISPLAY': ':0', 14:23:09 INFO - 'HOME': '/home/cltbld', 14:23:09 INFO - 'LANG': 'en_US.UTF-8', 14:23:09 INFO - 'LANGUAGE': 'en_US:en', 14:23:09 INFO - 'LOGNAME': 'cltbld', 14:23:09 INFO - 'MAIL': '/var/mail/cltbld', 14:23:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:23:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:09 INFO - 'MOZ_NO_REMOTE': '1', 14:23:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:23:09 INFO - 'NO_EM_RESTART': '1', 14:23:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:09 INFO - 'PWD': '/builds/slave/test', 14:23:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:23:09 INFO - 'SHELL': '/bin/bash', 14:23:09 INFO - 'SHLVL': '1', 14:23:09 INFO - 'TERM': 'linux', 14:23:09 INFO - 'TMOUT': '86400', 14:23:09 INFO - 'USER': 'cltbld', 14:23:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525', 14:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:09 INFO - '_': '/tools/buildbot/bin/python'} 14:23:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:23:09 INFO - mozversion application_buildid: 20160822111416 14:23:09 INFO - mozversion application_changeset: 2a0981531355dc77614e72c72fda7498bb074ffe 14:23:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:23:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:09 INFO - mozversion application_name: Firefox 14:23:09 INFO - mozversion application_remotingname: firefox-dev 14:23:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:23:09 INFO - mozversion application_vendor: Mozilla 14:23:09 INFO - mozversion application_version: 50.0a2 14:23:09 INFO - mozversion platform_buildid: 20160822111416 14:23:09 INFO - mozversion platform_changeset: 2a0981531355dc77614e72c72fda7498bb074ffe 14:23:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:23:09 INFO - mozversion platform_version: 50.0a2 14:23:09 INFO - using testdate: 1471900989 14:23:09 INFO - actual date: 1471900989 14:23:09 INFO - starting webserver on 'localhost:57367' 14:23:09 INFO - SUITE-START | Running 2 tests 14:23:09 INFO - TEST-START | dromaeo_css 14:23:09 INFO - operating with platform_type : linux_ 14:23:09 INFO - Initialising browser for dromaeo_css test... 14:23:09 INFO - TEST-INFO | started process 11102 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppdwVbf/profile http://localhost:57367/getInfo.html) 14:23:13 INFO - PROCESS | 11102 | __metrics Screen width/height:1600/1200 14:23:13 INFO - PROCESS | 11102 | colorDepth:24 14:23:13 INFO - PROCESS | 11102 | Browser inner width/height: 1024/697 14:23:13 INFO - PROCESS | 11102 | __metrics 14:23:14 ERROR - PROCESS | 11102 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:23:15 INFO - TEST-INFO | 11102: exit 0 14:23:15 INFO - Browser initialized. 14:23:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:15 INFO - TEST-INFO | started process 11228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppdwVbf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:27 INFO - PROCESS | 11228 | RSS: Main: 152944640 14:23:27 INFO - PROCESS | 11228 | 14:24:16 INFO - PROCESS | 11228 | 1471901056595 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:26:24 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57367/tests/dromaeo/cssquery-ext.html) 14:26:26 INFO - PROCESS | 11228 | RSS: Main: 158253056 14:26:26 INFO - PROCESS | 11228 | 14:29:28 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-ext.html (next: http://localhost:57367/tests/dromaeo/cssquery-jquery.html) 14:29:28 INFO - PROCESS | 11228 | RSS: Main: 154398720 14:29:28 INFO - PROCESS | 11228 | 14:32:30 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57367/tests/dromaeo/cssquery-mootools.html) 14:32:31 INFO - PROCESS | 11228 | RSS: Main: 154451968 14:32:31 INFO - PROCESS | 11228 | 14:35:32 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57367/tests/dromaeo/cssquery-prototype.html) 14:35:33 INFO - PROCESS | 11228 | RSS: Main: 153333760 14:35:33 INFO - PROCESS | 11228 | 14:38:35 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57367/tests/dromaeo/cssquery-yui.html) 14:38:36 INFO - PROCESS | 11228 | RSS: Main: 155840512 14:38:36 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | Cycle 1(1): loaded http://localhost:57367/tests/dromaeo/cssquery-yui.html (next: http://localhost:57367/tests/dromaeo/cssquery-dojo.html) 14:41:39 INFO - PROCESS | 11228 | RSS: Main: 155672576 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | __start_tp_report 14:41:39 INFO - PROCESS | 11228 | _x_x_mozilla_page_load 14:41:39 INFO - PROCESS | 11228 | _x_x_mozilla_page_load_details 14:41:39 INFO - PROCESS | 11228 | |i|pagename|runs| 14:41:39 INFO - PROCESS | 11228 | |0;dojo.html;3359.6136444308904;3384.255463797964;3385.4583266677328;3415.5901291844953;3419.504171895075;5788.363280039192;5838.270216222967;5839.503642190419;5854.677992710399;5888.057910734284;5673.716314184295;5674.460926212014;5718.056520674094;5723.2559767230305;5740.196372507842;5163.664361816479;5197.220416937471;5217.843464696065;5229.843104706865;5259.815906443275;5207.765650545734;5214.8696282592855;5232.45059268777;5264.473552644743;5284.894302113956;5209.7134657593815;5213.452587478317;5243.973780131094;5295.258663787073;5365.973170134163;5274.182501712241;5288.709120998344;5301.390340110898;5309.920351194735;5321.122014867542;4991.026749783794;5139.537441630271;5154.098032844275;5163.793448262065;5264.447233040514;5697.6866272355;5804.88390232193;5824.970875145597;5829.271341082364;5829.358770535237;6249.156363890907;6279.623222606612;6294.590851594632;6307;6324.33594472583;6044.063170208625;6129.1725617041575;6291.245050593912;6304.05439184126;6316.842078948017;5624.29696287964;5710.371859095496;5716.256886604715;5736;5758.222639943638;6262.091996660459;6278.372162783777;6281.560290779602;6297.685115744194;6301.8109456716375;5948.643081415129;5955.76176952917;5956.285245770535;6029.60807547508;6045.063015232646;6044.153818465418;6137.169688795662;6148.139260503443;6150.846228844314;6171.537134714825;6060.484858787053;6125.908111378333;6136.570440069241;6136.754529818846;6139.84650383744;5937.168796368512;6042.788502402394;6127.20346354971;6128.724207410677;6138.785142519991;4695.295705644181;4721.055788842245;4732.976335118303;4744.525547445297;4745.335653008581;4217.915641687149;4220.345846393814;4229.746215227096;4229.894252643647;4240.491141063092;5775.740091695884;5810.070388737781;5937.851553711105;5958.404159584094;5959.612625179349;6130.30641557605;6141.240538582545;6160.10678451631;6169.8457538561;6178.5366097542865;5856.8535786604825;5860.033094539353;5873.471387575139;5876.236089308448;5885.558583106199;5242.135047717084;5268.341457317835;5275.973620131875;5306.124489459196;5317.7872885084935;6318.747250109944;6402.775902843378;6415.422611964946;6424.678766061678;6482.222133344029;6233.501319894307;6236.844078897973;6238.656873871981;6238.719257633326;6252.187215661935;6214.223222097238;6361.745530178926;6383.36166383358;6462.256840463218;6474.287828338973;6130.36756271497;6145.938540614716;6164.167837341902;6172.629642221481;6182.814515564541;6215.844603884939;6291.150694656346;6297.4647154992335;6307.148534947723;6315.5894866833505;5831.854203644943;5961.523078153824;5999.370066142992;6108.083787431743;6142.3857614240005;5964.075568286749;5987.910181347371;5988.640681559121;5997.31030931431;6007.459328660267;5905;5910.7340169693325;5934.703264836655;5962.880742385215;5963.344032156372;5966.910496342472;5974.701264936823;6016.819495415145;6032.939670603413;6061.787837425669;5994.370591088047;5996.130561068533;6008.699565021819;6028.15605815195;6036.607620504653 14:41:39 INFO - PROCESS | 11228 | |1;ext.html;576.1357963055417;646.2632598837325;656.9934300656994;666.9666516674168;713.4613366907984;42130.789346053265;46112.308315375296;46585.76707116464;46731.76634116829;46738.29892551606;45578.54421455784;46093.7695311523;46349.53650463495;46484;46498.30252546206;8992.190702836728;9069.455832650063;9157.496337701425;9183.448993060405;9200.585973631203;9191.08089191079;9199.540022998857;9199.954000230024;9224.123708247706;9248.6300547978;9305.720828375126;9317.394369366004;9331.39345942515;9365.578548965314;9373.67192148275;9191.862122068142;9203.585838637244;9215.631374745002;9237.12247336502;9290.535473226379;8961.551922403854;9081.45511269326;9102.226310763594;9119;9127.406718563272;44717.77641111774;45147.32279015817;45196.54803451989;45285.77357113226;45561;3988.641022307977;4070.816813243411;4105;4173.144505376391;4378.408914796493;35277.47083793745;35657;36470;36575.63424365749;36606.63393366086;4740.857774266778;4755.048990201939;4764.809407623691;4777.928331075037;5042.193249080129;387705;387785;387859;388496;388555.0572247149;38364.42453363202;64644.35355646384;64724.35275647278;64963.35036649573;65102.674486627264;39077.21845563129;41044.38433423501;41088.79455602703;41170.38244426336;41286.58713412887;16827.32690692383;17022.233999470052;17104.828951710573;17109.828901711073;17126.886752361057;14410.991230613967;14539.41842326295;14560.126392416489;14606.780898286534;14607.123572585679;9004.36969412135;9226.07739226072;9244.3528952974;9292.814143717087;9308.720738377859;3520.436730123168;3536.5579302587175;3546.3261980223674;3559.5906470755676;3593.2633810068874;1082.767205050918;1101.5483651702923;1125.9437028148723;1138.4933704501418;1163.906887449002;33467.66532334645;34925.65074349275;35000.12499687477;35135.4729679055;35154.296914061575;1185.359905650956;1215.2222577550374;1255.7802384582662;1260.2816394655142;1281.9102662813514;17632.20655070525;18208.18063187133;18349.3577724779;18392.632147356977;18604;665783;672194;672914;674069;679350;64836.67581662061;65297.673511632136;65377.673111635086;65388.67305663441;65397.34602654008;1451.9545926932592;1453.8199811359098;1463.714575657758;1467.4570408948757;1481.7555103408033;33133.83433082819;33714;33825.661743383236;33967.15082122868;34071.31857362889;49771.25343119784;49974.50025499699;50360.496395037044;56357.71821140868;56668;426342;427523;428899;429620;430528;30979.69020309768;31137.53293700551;31139.377212455172;31140;31406.842965785025;51908.74045629748;52097.73951130372;52485.475145248056;52602.47397525976;52971.20543191932;44694.77652611716;45334.77332613448;46262.306065408375;46423.76788116038;47423.051538968335;44034.634876584605;45162;46137.76931115323;46257.537424626666;47164.292535611305;38236.23527529378;43217.135657287305;43891;44149.11701766011;44506.10987780291;38665;38811.417828732025;40434.79782601068;42756.35865461958;43512.782436088884;29441.85279073591;29878.551821722256;30192.396152076395;30526.694733052384;30550.694493054783 14:41:39 INFO - PROCESS | 11228 | |2;jquery.html;406.77830582332626;412.41437159233885;416.3068490962547;417.53236375259706;432.59552318582126;19469;20409.897950510247;21026.684599730997;21636.675449868264;21977.120915163374;19531.804681953177;19945.900270498627;20110.89944550279;20175.29386471474;20442.693359599616;5643.520300774439;5827.125931110346;5838.532917366621;5840.8247752567495;5852.531797456193;5858.41415858415;5881.588288819784;5885.117232415129;5925.407459254062;5948.286205655306;5657.886842263173;5856.619319744203;5867.677277749722;5871.060630299153;5877.0009098453365;5766.769329226826;5770.653760774367;5805.042168042268;5846.853828654275;5849.853753656172;5257.579393648537;5262.947370526284;5265.052290587692;5266.552343050846;5271;24450;25110.874445627654;25274.368140796443;25438.491230175478;25445.618315725278;2650.9337266568295;2660.720624334456;2861.4992376334058;3278.2623909620384;3359.5296658467832;15785.60535986598;15960;15968.920155399148;16072;16136.112513811735;2021.676531754912;2034.6947957806299;2053.938381848532;2142.9571408571896;2147.892605369725;87418;88054.55972720159;88828;88834.55582722109;90627.54686226592;10967.396793176453;11081.5567377304;11246.49390777417;11259.268147570381;11301.773964520664;16004.919975400282;16061.277242524115;16067;16106.75839862403;16107.677846443006;8973.955130224438;9019.233365164035;9093.136152065543;9174.311926605531;9227.354085214105;8984.640614375483;8985;8990.460572365679;9239.953800230956;9257.35198536096;6998.545094568837;7020.684069216897;7022.859542809115;7086.610236437045;7121.109861267341;2024.301615942527;2042.3056160905066;2044.8364130869495;2056.979430205679;2076.4497408186844;985.9605615775289;1056.2395075545594;1110.8833572474934;1113.3431275547466;1116.5924437580225;21216.681749773765;21220.575588488144;21347.150259067603;21378.251761188596;21537.89231053866;1062.0972173652488;1083.6748975307376;1111.2776695148218;1139.3619573039125;1162.0297051961538;11028;12152;12201.938990304992;12224.633261002184;12393.814092788616;524521;547516;567021;571265;580200;70516;88406.55796721103;89098.55450722834;89883;91134;1074.6722249713755;1134.4611309628026;1159.907207423404;1161.6689243565543;1167.270455965022;18156;18365.63268734591;18450.538736531693;18773.530661733566;19133.904330478257;41099.383509246785;44317;44892;45207;45432.545674544155;58626.70686646539;59583.404165957785;59652.70173649104;60334.39665603288;60724.39275607364;8462.026866910384;8504.829903401773;8541.53021583819;8585.09856465087;8710.825783484168;12716.237025778482;12993.740125197632;13141.802872957105;13836;14135.717285654024;11792.115591330514;11796.94101529487;12059.76894274527;12188.20776649515;12498.750024999266;11810.940945295219;11882;11919.642410727693;11965.76068478643;11984.340861252715;12386.5664701735;12577.811332830199;12717.682057948256;12804.423800928795;13269.40287687037;7570.05374328209;7692.269234422721;8494.405391622528;8496.745097647081;9244.352895297265;11178.329300241687;11270;11319.264247823865;11525.596604118815;11645.76708465843 14:41:39 INFO - PROCESS | 11228 | |3;mootools.html;1527.5264667952936;1529.426465075597;1704.7869016372954;1826.65293594217;1829.917653705583;8533.957330213356;9139.908600913997;9239.584218710157;9872.753181170474;9876.901230987687;9693.273004524672;9707.369021013628;9788.902110978888;9801.558929848155;9818.11636952674;2403.2069417092316;2455.815813813962;2457.0908763757348;2459.6187590923437;2461.88921498533;2433.3065076453213;2450.4119011437306;2457.950840983179;2483.341914392687;2500.2624225853324;2432.5378178146098;2480.627905814133;2481.5285095831823;2485.3165379520688;2489.0914816092177;2402.507485965373;2403.375122468153;2403.843750156243;2412.107520217522;2419.879006049708;2388.092524840567;2388.2477019738726;2395.1497218487534;2403.267003563912;2403.7235717892318;9113.179813816789;9228.169464748205;9245.815083698355;9271.629134834599;9283.350165488418;5389.380221274564;5502.889942201174;5552.472515111058;5552.805651802168;5557.638753481011;3606.819659017065;3692.1692619160744;3748.5501739791125;3787.6023017583307;3839.212961342918;1192.4574318684977;1227.9815802762967;1316.3220941215186;1327.907046506745;1339.3169483563356;13762.587122386345;13778.9999999999;13804.585862424143;13889.583312500641;13903.09629874055;4723.740194289278;4758.643101767381;4960.70235785854;4967.875803104951;4978.90042199154;11386.202965792316;11405.828912566318;11432.314061156358;11440.713982150513;11452.599159029394;5433.864153396117;5436.293281873332;5442.482964118402;5452.427495113036;5455.945440545624;4571.1086338164405;4961.3798275215595;5006.674566153261;5041.218611115283;5049;4288.91422171561;4320.092780516064;4468.731876087445;4510.909781804346;4693.530646935347;1394.351626493665;1395.4557722488237;1418.4822539773115;1424.1241636393543;1438.992805035989;1035.6530562261576;1060.1783617696346;1066.9679909602723;1074.398336931321;1084.8589683341115;6397.648129352837;6573.1783527059115;6624.635645039472;6696.486129982488;6792.76225332111;460.23830560421885;462.34578072027705;465.183602777124;468.063872255489;473.3727810650901;4445.955540444555;4473.4408198975125;4519.728816271035;4578.771061446914;4613.5847773700525;11252.887471125348;11725.941370293265;12035.879641203652;12156.5137394505;12187.39063046844;11494.482748276349;11809.763804723858;11859.762804743857;11868.703282418008;11938.044956403466;8782.297416206688;8926.68756593516;8967.148120928501;9006.369554131148;9016.41393309446;6658.866822663616;6699.39705426514;6865.5537390069485;6891.173059232924;6895.862082758216;11090.722731931444;11147.777044458902;11159.609413670483;11235.269707468988;11273.549058037916;11047.226694131334;11091.833622495831;11163.72090697739;11246.212765106691;11341.943290283496;7712.768616941501;8569.742907712778;8606.139386061594;8670.523121228392;8771.649134034567;9459;9505.857412138685;9525.333226674065;9620.37467564606;9635.903640963501;8298.58507074656;8467.61895714682;8502.617382217692;8547.57262136903;8549.145085491402;8281.130481299619;8294.129116442688;8303.875441868498;8362.540060296742;8401.15988401155;7779.338756205786;7868.331191848757;7930.881036784568;7935.40484463656;7948.165442628672;5470.288862447856;5599.244102046335;5666.065099258741;5673.290838645169;5834.299884013945;5590.273264475592;5626.212330273683;5697.316322041382;5725.627834190765;5828.6502809831545 14:41:39 INFO - PROCESS | 11228 | |4;prototype.html;347.8486858216675;350.87543921907724;353.5209790733556;355.96414433997074;358.5715070490763;5813.854653633662;5899.115132730088;5901.380355062715;5945.732442040107;5956.702164891749;5783.479486846183;5789.536837053036;5792.362840087587;5907.734151963161;5924.526037916967;4349.934266104809;4489.910201795962;4506.233940230169;4513.6389088872975;4516.14193303273;4509.872531867033;4511.977440112795;4513.803841981878;4529.162105010567;4533.795979180944;4472.955270447286;4499.987502811874;4505.346724724913;4508;4519.16395466838;4385.802638881258;4476.2837945928495;4486.977565112186;4496;4529.977350113245;3940.7241493095494;3974.628753080183;3978.204359128186;3984.4820173377566;3989.8005099744896;5697.20538030519;5707.857303567444;5714.228579141804;5757.424257574251;5767.826965191051;5105.366282789509;5155.149400348938;5159.58723302135;5193.428722840485;5212.635115541913;6040.6776273049645;6054.939450605481;6058.3032951210735;6066.876593192347;6075.362086980847;4253.936190957138;4390.319500477431;4397.780110994438;4398.670099742532;4398.956010439919;6108.541859360521;6327.715252813635;6377.681115944184;6386.87226255477;6398.840028999266;4424.446944131983;4445.266531022345;4447.533009034069;4459.888502787457;4470.441194850644;4451.532589078165;4530.275155975094;4549.499555048942;4556.681032327771;4556.997460558672;5008.774605142778;5144.305518754995;5174.560162386164;5183.2743415921805;5208.114620514521;6111.633302001894;6112.17485639433;6118.479929205978;6129.264488261348;6137.232845894263;1543.4597890738153;1560.8048993875766;1570.7408277634063;1571.395012920034;1587.2539906244047;945.7541039329824;990.3018372047833;1001.2090448545713;1001.3191030099457;1013.9746506337401;5255.32127510744;5268.710220937808;5271.42014378418;5272.340957380327;5294.973525132426;5548.861278468071;5553.722313884289;5561.58288128392;5602.551795856321;5622.57023215838;4876.292937524039;5182.714950677673;5185.185185185231;5197.480251974848;5280.043175821552;2913.766898648103;2927.516959701668;2944.1461976026712;2952.4390365830423;2959.8224106553675;5661.838548666727;5694.889496548216;5701.686407247641;5710.259779844208;5726.484616384545;5962.522998160137;6410.166678331881;6504.05691174777;6524.804255872332;6532.934670653232;6531.518023151172;6617.272100068989;6620.205575330895;6645.800625981132;6647.5014373921185;6191.81424557264;6226.139793328166;6279.96860015697;6333;6386.425221730066;6220.533459990428;6259.906101408574;6304.432601065926;6315;6319.652419116993;6267.592606480746;6361.61830290184;6566.9014964774615;6569.521857582005;6574.506911981525;4471.329300604935;4477.79099643101;4503;4539.455265368177;4546.477155127202;6267.5299352549655;6310.5898116622275;6394.584352017104;6432;6469.644169570531;6172.753089876354;6190.597611155441;6207.720652570735;6218.5647004711045;6234.812955611339;5910.172575839472;6204.937950620436;6230.974933146655;6244.65654389013;6261.624302541862;6071;6112.449879510866;6124.724387402489;6125.387461253839;6192.721327540362;6265.248170219603;6290;6298.149749783721;6299.496040316855;6341.334159913327;5838.299404071538;6054.757809687563;6056.033298189693;6073.878522429615;6104.542159338156 14:41:39 INFO - PROCESS | 11228 | |5;yui.html;837.2548431895613;842.0989541190925;847.987280190797;848.966041358346;902.3773596218613;15952.202389880518;16914.323427062904;16974.915125424355;17150.914245428787;17160.22778974946;16032.679346413064;16644.251008704607;16754.329826806912;16772.245248963794;16822.24299906504;744.2222877093424;900.7838118851478;915.7390143809016;922.294444749767;922.3635691372965;887.3699673232013;892.2415946445534;905.1898550797033;908.8500397434416;910.8588168833842;943.1276069635594;947.947862867542;953.0612346838393;954.1555723184962;955.159459675488;929.251952178496;933.9906600933972;938.4134915677702;941.2140862379921;942.222666300305;687.5840116729356;689.9275576064491;691.2603514239765;691.8443350246155;692.3146085375492;16980.74528882068;16982.66034679312;17097.230624621923;17098.401555945482;17106.401275955282;16851.91574042122;17098.059606721625;17215.741763873426;17439.825601744073;17608.471745847644;13168.86831131686;13467.595972120755;13478.595642130751;13519.256440895746;14089.43642254319;4093.3246014407587;4095.0581366285624;4134.855280065213;4213.47331583552;4249;89390;89447;89626.5518672409;89934;94788.99999999932;13158.671033224055;13321.333933303296;13450.596482105553;13594.524191653245;13612.183269003892;25361.239162825146;25371.746282537308;25386.365340866625;25586.616200757002;25695.743042569335;9498.810023799486;9513.286503512265;9729.70810875675;9849.901500985041;9924.553395097239;5197.922031169535;5252.527272545489;5370.221317908881;5378.596605254622;5493.7802487900835;3887.2031233597045;3925.685945124383;3941.960580394217;3988.8603898863403;3996.7402118862183;653.8103949854489;662.5925056090566;665.9101021362151;681.6898311268361;692.2731132311154;704.7956092733152;711.9145702515627;764.9120351159688;810.7486679129489;824.2664029014182;9588.808223835484;10481.685549433529;10520.736981575368;10617.362958222377;11305.321680699184;700.7057036044873;761.9923800761921;778.3734094054228;811.1077814404226;811.6834434570522;5269.104252277122;5270.393904701008;5283.1546952487415;5283.682979021271;5372.973135134299;126350;131233;131537;132098;133712;97895;102689;103538.48230758798;105410;107025;89149.55425222962;90613.54693226492;91990;93158.53420732853;94382.99999999863;10235.692929212135;10359.482025898524;10469.31949423285;10543.94728026355;10562.894371056498;56260.71869640626;56343.71828140833;57697.4230257692;59258;59808.70095649494;76395;81951;86176;88136.559317203;88206;13472.663183420493;13805.24071176095;13829.654258643614;13836.930815345859;13915.652108696959;14994.850051499781;15218.923905380403;15231.771523427378;15344.30950607203;15643.217839108227;14557.12657240569;15600.297986590404;15837.920810395874;16412.343506260095;16810.211474935222;15402.691946161238;15722.449714259947;15947.362105516113;17121.400750973655;17263.82736172622;15487.5353739388;15870.9206453967;16303.046029781288;16317.850257388596;16496.83503165001;10357.378557286587;12418.192817466836;12494.562690305796;12617.93691031539;12776.808347874976;10790.088174816106;11586;12475.563355282522;12837.935810320889;12987.480500779862 14:41:39 INFO - PROCESS | 11228 | __end_tp_report 14:41:39 INFO - PROCESS | 11228 | __start_cc_report 14:41:39 INFO - PROCESS | 11228 | _x_x_mozilla_cycle_collect,351 14:41:39 INFO - PROCESS | 11228 | __end_cc_report 14:41:39 INFO - PROCESS | 11228 | __startTimestamp1471902099279__endTimestamp 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | ------- Summary: start ------- 14:41:39 INFO - PROCESS | 11228 | Number of tests: 6 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#0] dojo.html Cycles:165 Average:5736.20 Median:5959.61 stddev:649.48 (10.9%) stddev-sans-first:624.14 14:41:39 INFO - PROCESS | 11228 | Values: 3359.6 3384.3 3385.5 3415.6 3419.5 5788.4 5838.3 5839.5 5854.7 5888.1 5673.7 5674.5 5718.1 5723.3 5740.2 5163.7 5197.2 5217.8 5229.8 5259.8 5207.8 5214.9 5232.5 5264.5 5284.9 5209.7 5213.5 5244.0 5295.3 5366.0 5274.2 5288.7 5301.4 5309.9 5321.1 4991.0 5139.5 5154.1 5163.8 5264.4 5697.7 5804.9 5825.0 5829.3 5829.4 6249.2 6279.6 6294.6 6307.0 6324.3 6044.1 6129.2 6291.2 6304.1 6316.8 5624.3 5710.4 5716.3 5736.0 5758.2 6262.1 6278.4 6281.6 6297.7 6301.8 5948.6 5955.8 5956.3 6029.6 6045.1 6044.2 6137.2 6148.1 6150.8 6171.5 6060.5 6125.9 6136.6 6136.8 6139.8 5937.2 6042.8 6127.2 6128.7 6138.8 4695.3 4721.1 4733.0 4744.5 4745.3 4217.9 4220.3 4229.7 4229.9 4240.5 5775.7 5810.1 5937.9 5958.4 5959.6 6130.3 6141.2 6160.1 6169.8 6178.5 5856.9 5860.0 5873.5 5876.2 5885.6 5242.1 5268.3 5276.0 5306.1 5317.8 6318.7 6402.8 6415.4 6424.7 6482.2 6233.5 6236.8 6238.7 6238.7 6252.2 6214.2 6361.7 6383.4 6462.3 6474.3 6130.4 6145.9 6164.2 6172.6 6182.8 6215.8 6291.2 6297.5 6307.1 6315.6 5831.9 5961.5 5999.4 6108.1 6142.4 5964.1 5987.9 5988.6 5997.3 6007.5 5905.0 5910.7 5934.7 5962.9 5963.3 5966.9 5974.7 6016.8 6032.9 6061.8 5994.4 5996.1 6008.7 6028.2 6036.6 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#1] ext.html Cycles:180 Average:65568.10 Median:44091.88 stddev:136629.89 (309.9%) stddev-sans-first:136926.03 14:41:39 INFO - PROCESS | 11228 | Values: 576.1 646.3 657.0 667.0 713.5 42130.8 46112.3 46585.8 46731.8 46738.3 45578.5 46093.8 46349.5 46484.0 46498.3 8992.2 9069.5 9157.5 9183.4 9200.6 9191.1 9199.5 9200.0 9224.1 9248.6 9305.7 9317.4 9331.4 9365.6 9373.7 9191.9 9203.6 9215.6 9237.1 9290.5 8961.6 9081.5 9102.2 9119.0 9127.4 44717.8 45147.3 45196.5 45285.8 45561.0 3988.6 4070.8 4105.0 4173.1 4378.4 35277.5 35657.0 36470.0 36575.6 36606.6 4740.9 4755.0 4764.8 4777.9 5042.2 387705.0 387785.0 387859.0 388496.0 388555.1 38364.4 64644.4 64724.4 64963.4 65102.7 39077.2 41044.4 41088.8 41170.4 41286.6 16827.3 17022.2 17104.8 17109.8 17126.9 14411.0 14539.4 14560.1 14606.8 14607.1 9004.4 9226.1 9244.4 9292.8 9308.7 3520.4 3536.6 3546.3 3559.6 3593.3 1082.8 1101.5 1125.9 1138.5 1163.9 33467.7 34925.7 35000.1 35135.5 35154.3 1185.4 1215.2 1255.8 1260.3 1281.9 17632.2 18208.2 18349.4 18392.6 18604.0 665783.0 672194.0 672914.0 674069.0 679350.0 64836.7 65297.7 65377.7 65388.7 65397.3 1452.0 1453.8 1463.7 1467.5 1481.8 33133.8 33714.0 33825.7 33967.2 34071.3 49771.3 49974.5 50360.5 56357.7 56668.0 426342.0 427523.0 428899.0 429620.0 430528.0 30979.7 31137.5 31139.4 31140.0 31406.8 51908.7 52097.7 52485.5 52602.5 52971.2 44694.8 45334.8 46262.3 46423.8 47423.1 44034.6 45162.0 46137.8 46257.5 47164.3 38236.2 43217.1 43891.0 44149.1 44506.1 38665.0 38811.4 40434.8 42756.4 43512.8 29441.9 29878.6 30192.4 30526.7 30550.7 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#2] jquery.html Cycles:180 Average:31691.31 Median:25192.62 stddev:91680.16 (363.9%) stddev-sans-first:91907.26 14:41:39 INFO - PROCESS | 11228 | Values: 406.8 412.4 416.3 417.5 432.6 19469.0 20409.9 21026.7 21636.7 21977.1 19531.8 19945.9 20110.9 20175.3 20442.7 5643.5 5827.1 5838.5 5840.8 5852.5 5858.4 5881.6 5885.1 5925.4 5948.3 5657.9 5856.6 5867.7 5871.1 5877.0 5766.8 5770.7 5805.0 5846.9 5849.9 5257.6 5262.9 5265.1 5266.6 5271.0 24450.0 25110.9 25274.4 25438.5 25445.6 2650.9 2660.7 2861.5 3278.3 3359.5 15785.6 15960.0 15968.9 16072.0 16136.1 2021.7 2034.7 2053.9 2143.0 2147.9 87418.0 88054.6 88828.0 88834.6 90627.5 10967.4 11081.6 11246.5 11259.3 11301.8 16004.9 16061.3 16067.0 16106.8 16107.7 8974.0 9019.2 9093.1 9174.3 9227.4 8984.6 8985.0 8990.5 9240.0 9257.4 6998.5 7020.7 7022.9 7086.6 7121.1 2024.3 2042.3 2044.8 2057.0 2076.4 986.0 1056.2 1110.9 1113.3 1116.6 21216.7 21220.6 21347.2 21378.3 21537.9 1062.1 1083.7 1111.3 1139.4 1162.0 11028.0 12152.0 12201.9 12224.6 12393.8 524521.0 547516.0 567021.0 571265.0 580200.0 70516.0 88406.6 89098.6 89883.0 91134.0 1074.7 1134.5 1159.9 1161.7 1167.3 18156.0 18365.6 18450.5 18773.5 19133.9 41099.4 44317.0 44892.0 45207.0 45432.5 58626.7 59583.4 59652.7 60334.4 60724.4 8462.0 8504.8 8541.5 8585.1 8710.8 12716.2 12993.7 13141.8 13836.0 14135.7 11792.1 11796.9 12059.8 12188.2 12498.8 11810.9 11882.0 11919.6 11965.8 11984.3 12386.6 12577.8 12717.7 12804.4 13269.4 7570.1 7692.3 8494.4 8496.7 9244.4 11178.3 11270.0 11319.3 11525.6 11645.8 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#3] mootools.html Cycles:180 Average:6321.85 Median:2537.97 stddev:3668.49 (144.5%) stddev-sans-first:3661.09 14:41:39 INFO - PROCESS | 11228 | Values: 1527.5 1529.4 1704.8 1826.7 1829.9 8534.0 9139.9 9239.6 9872.8 9876.9 9693.3 9707.4 9788.9 9801.6 9818.1 2403.2 2455.8 2457.1 2459.6 2461.9 2433.3 2450.4 2458.0 2483.3 2500.3 2432.5 2480.6 2481.5 2485.3 2489.1 2402.5 2403.4 2403.8 2412.1 2419.9 2388.1 2388.2 2395.1 2403.3 2403.7 9113.2 9228.2 9245.8 9271.6 9283.4 5389.4 5502.9 5552.5 5552.8 5557.6 3606.8 3692.2 3748.6 3787.6 3839.2 1192.5 1228.0 1316.3 1327.9 1339.3 13762.6 13779.0 13804.6 13889.6 13903.1 4723.7 4758.6 4960.7 4967.9 4978.9 11386.2 11405.8 11432.3 11440.7 11452.6 5433.9 5436.3 5442.5 5452.4 5455.9 4571.1 4961.4 5006.7 5041.2 5049.0 4288.9 4320.1 4468.7 4510.9 4693.5 1394.4 1395.5 1418.5 1424.1 1439.0 1035.7 1060.2 1067.0 1074.4 1084.9 6397.6 6573.2 6624.6 6696.5 6792.8 460.2 462.3 465.2 468.1 473.4 4446.0 4473.4 4519.7 4578.8 4613.6 11252.9 11725.9 12035.9 12156.5 12187.4 11494.5 11809.8 11859.8 11868.7 11938.0 8782.3 8926.7 8967.1 9006.4 9016.4 6658.9 6699.4 6865.6 6891.2 6895.9 11090.7 11147.8 11159.6 11235.3 11273.5 11047.2 11091.8 11163.7 11246.2 11341.9 7712.8 8569.7 8606.1 8670.5 8771.6 9459.0 9505.9 9525.3 9620.4 9635.9 8298.6 8467.6 8502.6 8547.6 8549.1 8281.1 8294.1 8303.9 8362.5 8401.2 7779.3 7868.3 7930.9 7935.4 7948.2 5470.3 5599.2 5666.1 5673.3 5834.3 5590.3 5626.2 5697.3 5725.6 5828.7 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#4] prototype.html Cycles:180 Average:5079.41 Median:5699.45 stddev:1520.09 (26.7%) stddev-sans-first:1482.29 14:41:39 INFO - PROCESS | 11228 | Values: 347.8 350.9 353.5 356.0 358.6 5813.9 5899.1 5901.4 5945.7 5956.7 5783.5 5789.5 5792.4 5907.7 5924.5 4349.9 4489.9 4506.2 4513.6 4516.1 4509.9 4512.0 4513.8 4529.2 4533.8 4473.0 4500.0 4505.3 4508.0 4519.2 4385.8 4476.3 4487.0 4496.0 4530.0 3940.7 3974.6 3978.2 3984.5 3989.8 5697.2 5707.9 5714.2 5757.4 5767.8 5105.4 5155.1 5159.6 5193.4 5212.6 6040.7 6054.9 6058.3 6066.9 6075.4 4253.9 4390.3 4397.8 4398.7 4399.0 6108.5 6327.7 6377.7 6386.9 6398.8 4424.4 4445.3 4447.5 4459.9 4470.4 4451.5 4530.3 4549.5 4556.7 4557.0 5008.8 5144.3 5174.6 5183.3 5208.1 6111.6 6112.2 6118.5 6129.3 6137.2 1543.5 1560.8 1570.7 1571.4 1587.3 945.8 990.3 1001.2 1001.3 1014.0 5255.3 5268.7 5271.4 5272.3 5295.0 5548.9 5553.7 5561.6 5602.6 5622.6 4876.3 5182.7 5185.2 5197.5 5280.0 2913.8 2927.5 2944.1 2952.4 2959.8 5661.8 5694.9 5701.7 5710.3 5726.5 5962.5 6410.2 6504.1 6524.8 6532.9 6531.5 6617.3 6620.2 6645.8 6647.5 6191.8 6226.1 6280.0 6333.0 6386.4 6220.5 6259.9 6304.4 6315.0 6319.7 6267.6 6361.6 6566.9 6569.5 6574.5 4471.3 4477.8 4503.0 4539.5 4546.5 6267.5 6310.6 6394.6 6432.0 6469.6 6172.8 6190.6 6207.7 6218.6 6234.8 5910.2 6204.9 6231.0 6244.7 6261.6 6071.0 6112.4 6124.7 6125.4 6192.7 6265.2 6290.0 6298.1 6299.5 6341.3 5838.3 6054.8 6056.0 6073.9 6104.5 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - PROCESS | 11228 | [#5] yui.html Cycles:180 Average:23260.70 Median:3965.41 stddev:33238.62 (838.2%) stddev-sans-first:33289.22 14:41:39 INFO - PROCESS | 11228 | Values: 837.3 842.1 848.0 849.0 902.4 15952.2 16914.3 16974.9 17150.9 17160.2 16032.7 16644.3 16754.3 16772.2 16822.2 744.2 900.8 915.7 922.3 922.4 887.4 892.2 905.2 908.9 910.9 943.1 947.9 953.1 954.2 955.2 929.3 934.0 938.4 941.2 942.2 687.6 689.9 691.3 691.8 692.3 16980.7 16982.7 17097.2 17098.4 17106.4 16851.9 17098.1 17215.7 17439.8 17608.5 13168.9 13467.6 13478.6 13519.3 14089.4 4093.3 4095.1 4134.9 4213.5 4249.0 89390.0 89447.0 89626.6 89934.0 94789.0 13158.7 13321.3 13450.6 13594.5 13612.2 25361.2 25371.7 25386.4 25586.6 25695.7 9498.8 9513.3 9729.7 9849.9 9924.6 5197.9 5252.5 5370.2 5378.6 5493.8 3887.2 3925.7 3942.0 3988.9 3996.7 653.8 662.6 665.9 681.7 692.3 704.8 711.9 764.9 810.7 824.3 9588.8 10481.7 10520.7 10617.4 11305.3 700.7 762.0 778.4 811.1 811.7 5269.1 5270.4 5283.2 5283.7 5373.0 126350.0 131233.0 131537.0 132098.0 133712.0 97895.0 102689.0 103538.5 105410.0 107025.0 89149.6 90613.5 91990.0 93158.5 94383.0 10235.7 10359.5 10469.3 10543.9 10562.9 56260.7 56343.7 57697.4 59258.0 59808.7 76395.0 81951.0 86176.0 88136.6 88206.0 13472.7 13805.2 13829.7 13836.9 13915.7 14994.9 15218.9 15231.8 15344.3 15643.2 14557.1 15600.3 15837.9 16412.3 16810.2 15402.7 15722.4 15947.4 17121.4 17263.8 15487.5 15870.9 16303.0 16317.9 16496.8 10357.4 12418.2 12494.6 12617.9 12776.8 10790.1 11586.0 12475.6 12837.9 12987.5 14:41:39 INFO - PROCESS | 11228 | -------- Summary: end -------- 14:41:39 INFO - PROCESS | 11228 | 14:41:39 INFO - TEST-INFO | 11228: exit 0 14:41:39 INFO - TEST-OK | dromaeo_css | took 1109951ms 14:41:39 INFO - TEST-START | kraken 14:41:39 INFO - operating with platform_type : linux_ 14:41:39 INFO - Initialising browser for kraken test... 14:41:39 INFO - TEST-INFO | started process 11491 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQIAW3/profile http://localhost:57367/getInfo.html) 14:41:43 INFO - PROCESS | 11491 | __metrics Screen width/height:1600/1200 14:41:43 INFO - PROCESS | 11491 | colorDepth:24 14:41:43 INFO - PROCESS | 11491 | Browser inner width/height: 1024/697 14:41:43 INFO - PROCESS | 11491 | __metrics 14:41:44 ERROR - PROCESS | 11491 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 14:41:45 INFO - TEST-INFO | 11491: exit 0 14:41:45 INFO - Browser initialized. 14:41:45 INFO - Running cycle 1/1 for kraken test... 14:41:45 INFO - TEST-INFO | started process 11617 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQIAW3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:41:56 INFO - PROCESS | 11617 | RSS: Main: 152539136 14:41:56 INFO - PROCESS | 11617 | 14:41:59 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57367/tests/kraken/driver.html?testName=audio-beat-detection) 14:42:00 INFO - PROCESS | 11617 | RSS: Main: 157786112 14:42:00 INFO - PROCESS | 11617 | 14:42:02 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57367/tests/kraken/driver.html?testName=audio-dft) 14:42:03 INFO - PROCESS | 11617 | RSS: Main: 156942336 14:42:03 INFO - PROCESS | 11617 | 14:42:06 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57367/tests/kraken/driver.html?testName=audio-fft) 14:42:06 INFO - PROCESS | 11617 | RSS: Main: 157675520 14:42:06 INFO - PROCESS | 11617 | 14:42:09 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57367/tests/kraken/driver.html?testName=audio-oscillator) 14:42:09 INFO - PROCESS | 11617 | RSS: Main: 157515776 14:42:09 INFO - PROCESS | 11617 | 14:42:11 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:12 INFO - PROCESS | 11617 | RSS: Main: 157220864 14:42:12 INFO - PROCESS | 11617 | 14:42:15 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57367/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:16 INFO - PROCESS | 11617 | RSS: Main: 156336128 14:42:16 INFO - PROCESS | 11617 | 14:42:19 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57367/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:20 INFO - PROCESS | 11617 | RSS: Main: 155504640 14:42:20 INFO - PROCESS | 11617 | 14:42:23 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57367/tests/kraken/driver.html?testName=json-parse-financial) 14:42:23 INFO - PROCESS | 11617 | RSS: Main: 156712960 14:42:23 INFO - PROCESS | 11617 | 14:42:25 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:42:25 INFO - PROCESS | 11617 | RSS: Main: 156688384 14:42:25 INFO - PROCESS | 11617 | 14:42:27 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:42:28 INFO - PROCESS | 11617 | RSS: Main: 156856320 14:42:28 INFO - PROCESS | 11617 | 14:42:30 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:42:30 INFO - PROCESS | 11617 | RSS: Main: 157106176 14:42:30 INFO - PROCESS | 11617 | 14:42:33 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:42:34 INFO - PROCESS | 11617 | RSS: Main: 156983296 14:42:34 INFO - PROCESS | 11617 | 14:42:36 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:42:37 INFO - PROCESS | 11617 | RSS: Main: 157478912 14:42:37 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | Cycle 1(1): loaded http://localhost:57367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57367/tests/kraken/driver.html?testName=ai-astar) 14:42:39 INFO - PROCESS | 11617 | RSS: Main: 158023680 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | __start_tp_report 14:42:39 INFO - PROCESS | 11617 | _x_x_mozilla_page_load 14:42:39 INFO - PROCESS | 11617 | _x_x_mozilla_page_load_details 14:42:39 INFO - PROCESS | 11617 | |i|pagename|runs| 14:42:39 INFO - PROCESS | 11617 | |0;ai-astar;101;113;106;111;112;112;113;112;110;112 14:42:39 INFO - PROCESS | 11617 | |1;audio-beat-detection;145;114;142;166;119;142;157;119;179;118 14:42:39 INFO - PROCESS | 11617 | |2;audio-dft;163;162;164;163;163;161;162;163;165;167 14:42:39 INFO - PROCESS | 11617 | |3;audio-fft;76;75;75;173;75;76;74;76;170;76 14:42:39 INFO - PROCESS | 11617 | |4;audio-oscillator;84;83;145;84;83;83;144;83;83;144 14:42:39 INFO - PROCESS | 11617 | |5;imaging-gaussian-blur;118;117;117;121;117;117;117;117;118;117 14:42:39 INFO - PROCESS | 11617 | |6;imaging-darkroom;148;147;149;147;148;147;147;147;151;152 14:42:39 INFO - PROCESS | 11617 | |7;imaging-desaturate;95;95;98;96;96;96;95;95;95;95 14:42:39 INFO - PROCESS | 11617 | |8;json-parse-financial;52;53;49;50;53;50;52;51;51;51 14:42:39 INFO - PROCESS | 11617 | |9;json-stringify-tinderbox;61;60;59;62;60;58;59;60;61;60 14:42:39 INFO - PROCESS | 11617 | |10;stanford-crypto-aes;73;75;72;73;73;76;73;78;74;71 14:42:39 INFO - PROCESS | 11617 | |11;stanford-crypto-ccm;127;128;124;116;129;120;130;119;154;128 14:42:39 INFO - PROCESS | 11617 | |12;stanford-crypto-pbkdf2;155;153;156;154;152;151;156;154;155;151 14:42:39 INFO - PROCESS | 11617 | |13;stanford-crypto-sha256-iterative;77;69;61;61;68;66;60;63;61;64 14:42:39 INFO - PROCESS | 11617 | __end_tp_report 14:42:39 INFO - PROCESS | 11617 | __start_cc_report 14:42:39 INFO - PROCESS | 11617 | _x_x_mozilla_cycle_collect,852 14:42:39 INFO - PROCESS | 11617 | __end_cc_report 14:42:39 INFO - PROCESS | 11617 | __startTimestamp1471902159284__endTimestamp 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | ------- Summary: start ------- 14:42:39 INFO - PROCESS | 11617 | Number of tests: 14 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#0] ai-astar Cycles:10 Average:110.20 Median:112.00 stddev:3.82 (3.4%) stddev-sans-first:2.17 14:42:39 INFO - PROCESS | 11617 | Values: 101.0 113.0 106.0 111.0 112.0 112.0 113.0 112.0 110.0 112.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:143.50 stddev:22.51 (15.7%) stddev-sans-first:23.81 14:42:39 INFO - PROCESS | 11617 | Values: 145.0 114.0 142.0 166.0 119.0 142.0 157.0 119.0 179.0 118.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:1.70 (1.0%) stddev-sans-first:1.80 14:42:39 INFO - PROCESS | 11617 | Values: 163.0 162.0 164.0 163.0 163.0 161.0 162.0 163.0 165.0 167.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.50 stddev:40.54 (53.7%) stddev-sans-first:42.44 14:42:39 INFO - PROCESS | 11617 | Values: 76.0 75.0 75.0 173.0 75.0 76.0 74.0 76.0 170.0 76.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:83.00 stddev:29.49 (35.5%) stddev-sans-first:30.59 14:42:39 INFO - PROCESS | 11617 | Values: 84.0 83.0 145.0 84.0 83.0 83.0 144.0 83.0 83.0 144.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:117.00 stddev:1.26 (1.1%) stddev-sans-first:1.33 14:42:39 INFO - PROCESS | 11617 | Values: 118.0 117.0 117.0 121.0 117.0 117.0 117.0 117.0 118.0 117.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:1.83 (1.2%) stddev-sans-first:1.94 14:42:39 INFO - PROCESS | 11617 | Values: 148.0 147.0 149.0 147.0 148.0 147.0 147.0 147.0 151.0 152.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.50 stddev:0.97 (1.0%) stddev-sans-first:1.00 14:42:39 INFO - PROCESS | 11617 | Values: 95.0 95.0 98.0 96.0 96.0 96.0 95.0 95.0 95.0 95.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.50 stddev:1.32 (2.6%) stddev-sans-first:1.36 14:42:39 INFO - PROCESS | 11617 | Values: 52.0 53.0 49.0 50.0 53.0 50.0 52.0 51.0 51.0 51.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.15 (1.9%) stddev-sans-first:1.17 14:42:39 INFO - PROCESS | 11617 | Values: 61.0 60.0 59.0 62.0 60.0 58.0 59.0 60.0 61.0 60.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.50 stddev:2.04 (2.8%) stddev-sans-first:2.15 14:42:39 INFO - PROCESS | 11617 | Values: 73.0 75.0 72.0 73.0 73.0 76.0 73.0 78.0 74.0 71.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:128.00 stddev:10.46 (8.2%) stddev-sans-first:11.09 14:42:39 INFO - PROCESS | 11617 | Values: 127.0 128.0 124.0 116.0 129.0 120.0 130.0 119.0 154.0 128.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:154.50 stddev:1.89 (1.2%) stddev-sans-first:1.94 14:42:39 INFO - PROCESS | 11617 | Values: 155.0 153.0 156.0 154.0 152.0 151.0 156.0 154.0 155.0 151.0 14:42:39 INFO - PROCESS | 11617 | 14:42:39 INFO - PROCESS | 11617 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:5.25 (8.1%) stddev-sans-first:3.32 14:42:39 INFO - PROCESS | 11617 | Values: 77.0 69.0 61.0 61.0 68.0 66.0 60.0 63.0 61.0 64.0 14:42:39 INFO - PROCESS | 11617 | -------- Summary: end -------- 14:42:39 INFO - PROCESS | 11617 | 14:42:40 INFO - TEST-INFO | 11617: exit 0 14:42:40 INFO - TEST-OK | kraken | took 60284ms 14:42:40 INFO - SUITE-END | took 1170s 14:42:40 INFO - Completed test suite (00:19:30) 14:42:40 INFO - javascript benchmark 14:42:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7784.953745000266, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3359.6136444308904, 3384.255463797964, 3385.4583266677328, 3415.5901291844953, 3419.504171895075, 5788.363280039192, 5838.270216222967, 5839.503642190419, 5854.677992710399, 5888.057910734284, 5673.716314184295, 5674.460926212014, 5718.056520674094, 5723.2559767230305, 5740.196372507842, 5163.664361816479, 5197.220416937471, 5217.843464696065, 5229.843104706865, 5259.815906443275, 5207.765650545734, 5214.8696282592855, 5232.45059268777, 5264.473552644743, 5284.894302113956, 5209.7134657593815, 5213.452587478317, 5243.973780131094, 5295.258663787073, 5365.973170134163, 5274.182501712241, 5288.709120998344, 5301.390340110898, 5309.920351194735, 5321.122014867542, 4991.026749783794, 5139.537441630271, 5154.098032844275, 5163.793448262065, 5264.447233040514, 5697.6866272355, 5804.88390232193, 5824.970875145597, 5829.271341082364, 5829.358770535237, 6249.156363890907, 6279.623222606612, 6294.590851594632, 6307.0, 6324.33594472583, 6044.063170208625, 6129.1725617041575, 6291.245050593912, 6304.05439184126, 6316.842078948017, 5624.29696287964, 5710.371859095496, 5716.256886604715, 5736.0, 5758.222639943638, 6262.091996660459, 6278.372162783777, 6281.560290779602, 6297.685115744194, 6301.8109456716375, 5948.643081415129, 5955.76176952917, 5956.285245770535, 6029.60807547508, 6045.063015232646, 6044.153818465418, 6137.169688795662, 6148.139260503443, 6150.846228844314, 6171.537134714825, 6060.484858787053, 6125.908111378333, 6136.570440069241, 6136.754529818846, 6139.84650383744, 5937.168796368512, 6042.788502402394, 6127.20346354971, 6128.724207410677, 6138.785142519991, 4695.295705644181, 4721.055788842245, 4732.976335118303, 4744.525547445297, 4745.335653008581, 4217.915641687149, 4220.345846393814, 4229.746215227096, 4229.894252643647, 4240.491141063092, 5775.740091695884, 5810.070388737781, 5937.851553711105, 5958.404159584094, 5959.612625179349, 6130.30641557605, 6141.240538582545, 6160.10678451631, 6169.8457538561, 6178.5366097542865, 5856.8535786604825, 5860.033094539353, 5873.471387575139, 5876.236089308448, 5885.558583106199, 5242.135047717084, 5268.341457317835, 5275.973620131875, 5306.124489459196, 5317.7872885084935, 6318.747250109944, 6402.775902843378, 6415.422611964946, 6424.678766061678, 6482.222133344029, 6233.501319894307, 6236.844078897973, 6238.656873871981, 6238.719257633326, 6252.187215661935, 6214.223222097238, 6361.745530178926, 6383.36166383358, 6462.256840463218, 6474.287828338973, 6130.36756271497, 6145.938540614716, 6164.167837341902, 6172.629642221481, 6182.814515564541, 6215.844603884939, 6291.150694656346, 6297.4647154992335, 6307.148534947723, 6315.5894866833505, 5831.854203644943, 5961.523078153824, 5999.370066142992, 6108.083787431743, 6142.3857614240005, 5964.075568286749, 5987.910181347371, 5988.640681559121, 5997.31030931431, 6007.459328660267, 5905.0, 5910.7340169693325, 5934.703264836655, 5962.880742385215, 5963.344032156372, 5966.910496342472, 5974.701264936823, 6016.819495415145, 6032.939670603413, 6061.787837425669, 5994.370591088047, 5996.130561068533, 6008.699565021819, 6028.15605815195, 6036.607620504653], "value": 5692.844504967029, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.1357963055417, 646.2632598837325, 656.9934300656994, 666.9666516674168, 713.4613366907984, 42130.789346053265, 46112.308315375296, 46585.76707116464, 46731.76634116829, 46738.29892551606, 45578.54421455784, 46093.7695311523, 46349.53650463495, 46484.0, 46498.30252546206, 8992.190702836728, 9069.455832650063, 9157.496337701425, 9183.448993060405, 9200.585973631203, 9191.08089191079, 9199.540022998857, 9199.954000230024, 9224.123708247706, 9248.6300547978, 9305.720828375126, 9317.394369366004, 9331.39345942515, 9365.578548965314, 9373.67192148275, 9191.862122068142, 9203.585838637244, 9215.631374745002, 9237.12247336502, 9290.535473226379, 8961.551922403854, 9081.45511269326, 9102.226310763594, 9119.0, 9127.406718563272, 44717.77641111774, 45147.32279015817, 45196.54803451989, 45285.77357113226, 45561.0, 3988.641022307977, 4070.816813243411, 4105.0, 4173.144505376391, 4378.408914796493, 35277.47083793745, 35657.0, 36470.0, 36575.63424365749, 36606.63393366086, 4740.857774266778, 4755.048990201939, 4764.809407623691, 4777.928331075037, 5042.193249080129, 387705.0, 387785.0, 387859.0, 388496.0, 388555.0572247149, 38364.42453363202, 64644.35355646384, 64724.35275647278, 64963.35036649573, 65102.674486627264, 39077.21845563129, 41044.38433423501, 41088.79455602703, 41170.38244426336, 41286.58713412887, 16827.32690692383, 17022.233999470052, 17104.828951710573, 17109.828901711073, 17126.886752361057, 14410.991230613967, 14539.41842326295, 14560.126392416489, 14606.780898286534, 14607.123572585679, 9004.36969412135, 9226.07739226072, 9244.3528952974, 9292.814143717087, 9308.720738377859, 3520.436730123168, 3536.5579302587175, 3546.3261980223674, 3559.5906470755676, 3593.2633810068874, 1082.767205050918, 1101.5483651702923, 1125.9437028148723, 1138.4933704501418, 1163.906887449002, 33467.66532334645, 34925.65074349275, 35000.12499687477, 35135.4729679055, 35154.296914061575, 1185.359905650956, 1215.2222577550374, 1255.7802384582662, 1260.2816394655142, 1281.9102662813514, 17632.20655070525, 18208.18063187133, 18349.3577724779, 18392.632147356977, 18604.0, 665783.0, 672194.0, 672914.0, 674069.0, 679350.0, 64836.67581662061, 65297.673511632136, 65377.673111635086, 65388.67305663441, 65397.34602654008, 1451.9545926932592, 1453.8199811359098, 1463.714575657758, 1467.4570408948757, 1481.7555103408033, 33133.83433082819, 33714.0, 33825.661743383236, 33967.15082122868, 34071.31857362889, 49771.25343119784, 49974.50025499699, 50360.496395037044, 56357.71821140868, 56668.0, 426342.0, 427523.0, 428899.0, 429620.0, 430528.0, 30979.69020309768, 31137.53293700551, 31139.377212455172, 31140.0, 31406.842965785025, 51908.74045629748, 52097.73951130372, 52485.475145248056, 52602.47397525976, 52971.20543191932, 44694.77652611716, 45334.77332613448, 46262.306065408375, 46423.76788116038, 47423.051538968335, 44034.634876584605, 45162.0, 46137.76931115323, 46257.537424626666, 47164.292535611305, 38236.23527529378, 43217.135657287305, 43891.0, 44149.11701766011, 44506.10987780291, 38665.0, 38811.417828732025, 40434.79782601068, 42756.35865461958, 43512.782436088884, 29441.85279073591, 29878.551821722256, 30192.396152076395, 30526.694733052384, 30550.694493054783], "value": 20530.08372070161, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.77830582332626, 412.41437159233885, 416.3068490962547, 417.53236375259706, 432.59552318582126, 19469.0, 20409.897950510247, 21026.684599730997, 21636.675449868264, 21977.120915163374, 19531.804681953177, 19945.900270498627, 20110.89944550279, 20175.29386471474, 20442.693359599616, 5643.520300774439, 5827.125931110346, 5838.532917366621, 5840.8247752567495, 5852.531797456193, 5858.41415858415, 5881.588288819784, 5885.117232415129, 5925.407459254062, 5948.286205655306, 5657.886842263173, 5856.619319744203, 5867.677277749722, 5871.060630299153, 5877.0009098453365, 5766.769329226826, 5770.653760774367, 5805.042168042268, 5846.853828654275, 5849.853753656172, 5257.579393648537, 5262.947370526284, 5265.052290587692, 5266.552343050846, 5271.0, 24450.0, 25110.874445627654, 25274.368140796443, 25438.491230175478, 25445.618315725278, 2650.9337266568295, 2660.720624334456, 2861.4992376334058, 3278.2623909620384, 3359.5296658467832, 15785.60535986598, 15960.0, 15968.920155399148, 16072.0, 16136.112513811735, 2021.676531754912, 2034.6947957806299, 2053.938381848532, 2142.9571408571896, 2147.892605369725, 87418.0, 88054.55972720159, 88828.0, 88834.55582722109, 90627.54686226592, 10967.396793176453, 11081.5567377304, 11246.49390777417, 11259.268147570381, 11301.773964520664, 16004.919975400282, 16061.277242524115, 16067.0, 16106.75839862403, 16107.677846443006, 8973.955130224438, 9019.233365164035, 9093.136152065543, 9174.311926605531, 9227.354085214105, 8984.640614375483, 8985.0, 8990.460572365679, 9239.953800230956, 9257.35198536096, 6998.545094568837, 7020.684069216897, 7022.859542809115, 7086.610236437045, 7121.109861267341, 2024.301615942527, 2042.3056160905066, 2044.8364130869495, 2056.979430205679, 2076.4497408186844, 985.9605615775289, 1056.2395075545594, 1110.8833572474934, 1113.3431275547466, 1116.5924437580225, 21216.681749773765, 21220.575588488144, 21347.150259067603, 21378.251761188596, 21537.89231053866, 1062.0972173652488, 1083.6748975307376, 1111.2776695148218, 1139.3619573039125, 1162.0297051961538, 11028.0, 12152.0, 12201.938990304992, 12224.633261002184, 12393.814092788616, 524521.0, 547516.0, 567021.0, 571265.0, 580200.0, 70516.0, 88406.55796721103, 89098.55450722834, 89883.0, 91134.0, 1074.6722249713755, 1134.4611309628026, 1159.907207423404, 1161.6689243565543, 1167.270455965022, 18156.0, 18365.63268734591, 18450.538736531693, 18773.530661733566, 19133.904330478257, 41099.383509246785, 44317.0, 44892.0, 45207.0, 45432.545674544155, 58626.70686646539, 59583.404165957785, 59652.70173649104, 60334.39665603288, 60724.39275607364, 8462.026866910384, 8504.829903401773, 8541.53021583819, 8585.09856465087, 8710.825783484168, 12716.237025778482, 12993.740125197632, 13141.802872957105, 13836.0, 14135.717285654024, 11792.115591330514, 11796.94101529487, 12059.76894274527, 12188.20776649515, 12498.750024999266, 11810.940945295219, 11882.0, 11919.642410727693, 11965.76068478643, 11984.340861252715, 12386.5664701735, 12577.811332830199, 12717.682057948256, 12804.423800928795, 13269.40287687037, 7570.05374328209, 7692.269234422721, 8494.405391622528, 8496.745097647081, 9244.352895297265, 11178.329300241687, 11270.0, 11319.264247823865, 11525.596604118815, 11645.76708465843], "value": 9945.522322061004, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1527.5264667952936, 1529.426465075597, 1704.7869016372954, 1826.65293594217, 1829.917653705583, 8533.957330213356, 9139.908600913997, 9239.584218710157, 9872.753181170474, 9876.901230987687, 9693.273004524672, 9707.369021013628, 9788.902110978888, 9801.558929848155, 9818.11636952674, 2403.2069417092316, 2455.815813813962, 2457.0908763757348, 2459.6187590923437, 2461.88921498533, 2433.3065076453213, 2450.4119011437306, 2457.950840983179, 2483.341914392687, 2500.2624225853324, 2432.5378178146098, 2480.627905814133, 2481.5285095831823, 2485.3165379520688, 2489.0914816092177, 2402.507485965373, 2403.375122468153, 2403.843750156243, 2412.107520217522, 2419.879006049708, 2388.092524840567, 2388.2477019738726, 2395.1497218487534, 2403.267003563912, 2403.7235717892318, 9113.179813816789, 9228.169464748205, 9245.815083698355, 9271.629134834599, 9283.350165488418, 5389.380221274564, 5502.889942201174, 5552.472515111058, 5552.805651802168, 5557.638753481011, 3606.819659017065, 3692.1692619160744, 3748.5501739791125, 3787.6023017583307, 3839.212961342918, 1192.4574318684977, 1227.9815802762967, 1316.3220941215186, 1327.907046506745, 1339.3169483563356, 13762.587122386345, 13778.9999999999, 13804.585862424143, 13889.583312500641, 13903.09629874055, 4723.740194289278, 4758.643101767381, 4960.70235785854, 4967.875803104951, 4978.90042199154, 11386.202965792316, 11405.828912566318, 11432.314061156358, 11440.713982150513, 11452.599159029394, 5433.864153396117, 5436.293281873332, 5442.482964118402, 5452.427495113036, 5455.945440545624, 4571.1086338164405, 4961.3798275215595, 5006.674566153261, 5041.218611115283, 5049.0, 4288.91422171561, 4320.092780516064, 4468.731876087445, 4510.909781804346, 4693.530646935347, 1394.351626493665, 1395.4557722488237, 1418.4822539773115, 1424.1241636393543, 1438.992805035989, 1035.6530562261576, 1060.1783617696346, 1066.9679909602723, 1074.398336931321, 1084.8589683341115, 6397.648129352837, 6573.1783527059115, 6624.635645039472, 6696.486129982488, 6792.76225332111, 460.23830560421885, 462.34578072027705, 465.183602777124, 468.063872255489, 473.3727810650901, 4445.955540444555, 4473.4408198975125, 4519.728816271035, 4578.771061446914, 4613.5847773700525, 11252.887471125348, 11725.941370293265, 12035.879641203652, 12156.5137394505, 12187.39063046844, 11494.482748276349, 11809.763804723858, 11859.762804743857, 11868.703282418008, 11938.044956403466, 8782.297416206688, 8926.68756593516, 8967.148120928501, 9006.369554131148, 9016.41393309446, 6658.866822663616, 6699.39705426514, 6865.5537390069485, 6891.173059232924, 6895.862082758216, 11090.722731931444, 11147.777044458902, 11159.609413670483, 11235.269707468988, 11273.549058037916, 11047.226694131334, 11091.833622495831, 11163.72090697739, 11246.212765106691, 11341.943290283496, 7712.768616941501, 8569.742907712778, 8606.139386061594, 8670.523121228392, 8771.649134034567, 9459.0, 9505.857412138685, 9525.333226674065, 9620.37467564606, 9635.903640963501, 8298.58507074656, 8467.61895714682, 8502.617382217692, 8547.57262136903, 8549.145085491402, 8281.130481299619, 8294.129116442688, 8303.875441868498, 8362.540060296742, 8401.15988401155, 7779.338756205786, 7868.331191848757, 7930.881036784568, 7935.40484463656, 7948.165442628672, 5470.288862447856, 5599.244102046335, 5666.065099258741, 5673.290838645169, 5834.299884013945, 5590.273264475592, 5626.212330273683, 5697.316322041382, 5725.627834190765, 5828.6502809831545], "value": 4942.745199698677, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8486858216675, 350.87543921907724, 353.5209790733556, 355.96414433997074, 358.5715070490763, 5813.854653633662, 5899.115132730088, 5901.380355062715, 5945.732442040107, 5956.702164891749, 5783.479486846183, 5789.536837053036, 5792.362840087587, 5907.734151963161, 5924.526037916967, 4349.934266104809, 4489.910201795962, 4506.233940230169, 4513.6389088872975, 4516.14193303273, 4509.872531867033, 4511.977440112795, 4513.803841981878, 4529.162105010567, 4533.795979180944, 4472.955270447286, 4499.987502811874, 4505.346724724913, 4508.0, 4519.16395466838, 4385.802638881258, 4476.2837945928495, 4486.977565112186, 4496.0, 4529.977350113245, 3940.7241493095494, 3974.628753080183, 3978.204359128186, 3984.4820173377566, 3989.8005099744896, 5697.20538030519, 5707.857303567444, 5714.228579141804, 5757.424257574251, 5767.826965191051, 5105.366282789509, 5155.149400348938, 5159.58723302135, 5193.428722840485, 5212.635115541913, 6040.6776273049645, 6054.939450605481, 6058.3032951210735, 6066.876593192347, 6075.362086980847, 4253.936190957138, 4390.319500477431, 4397.780110994438, 4398.670099742532, 4398.956010439919, 6108.541859360521, 6327.715252813635, 6377.681115944184, 6386.87226255477, 6398.840028999266, 4424.446944131983, 4445.266531022345, 4447.533009034069, 4459.888502787457, 4470.441194850644, 4451.532589078165, 4530.275155975094, 4549.499555048942, 4556.681032327771, 4556.997460558672, 5008.774605142778, 5144.305518754995, 5174.560162386164, 5183.2743415921805, 5208.114620514521, 6111.633302001894, 6112.17485639433, 6118.479929205978, 6129.264488261348, 6137.232845894263, 1543.4597890738153, 1560.8048993875766, 1570.7408277634063, 1571.395012920034, 1587.2539906244047, 945.7541039329824, 990.3018372047833, 1001.2090448545713, 1001.3191030099457, 1013.9746506337401, 5255.32127510744, 5268.710220937808, 5271.42014378418, 5272.340957380327, 5294.973525132426, 5548.861278468071, 5553.722313884289, 5561.58288128392, 5602.551795856321, 5622.57023215838, 4876.292937524039, 5182.714950677673, 5185.185185185231, 5197.480251974848, 5280.043175821552, 2913.766898648103, 2927.516959701668, 2944.1461976026712, 2952.4390365830423, 2959.8224106553675, 5661.838548666727, 5694.889496548216, 5701.686407247641, 5710.259779844208, 5726.484616384545, 5962.522998160137, 6410.166678331881, 6504.05691174777, 6524.804255872332, 6532.934670653232, 6531.518023151172, 6617.272100068989, 6620.205575330895, 6645.800625981132, 6647.5014373921185, 6191.81424557264, 6226.139793328166, 6279.96860015697, 6333.0, 6386.425221730066, 6220.533459990428, 6259.906101408574, 6304.432601065926, 6315.0, 6319.652419116993, 6267.592606480746, 6361.61830290184, 6566.9014964774615, 6569.521857582005, 6574.506911981525, 4471.329300604935, 4477.79099643101, 4503.0, 4539.455265368177, 4546.477155127202, 6267.5299352549655, 6310.5898116622275, 6394.584352017104, 6432.0, 6469.644169570531, 6172.753089876354, 6190.597611155441, 6207.720652570735, 6218.5647004711045, 6234.812955611339, 5910.172575839472, 6204.937950620436, 6230.974933146655, 6244.65654389013, 6261.624302541862, 6071.0, 6112.449879510866, 6124.724387402489, 6125.387461253839, 6192.721327540362, 6265.248170219603, 6290.0, 6298.149749783721, 6299.496040316855, 6341.334159913327, 5838.299404071538, 6054.757809687563, 6056.033298189693, 6073.878522429615, 6104.542159338156], "value": 4590.9979751127, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.2548431895613, 842.0989541190925, 847.987280190797, 848.966041358346, 902.3773596218613, 15952.202389880518, 16914.323427062904, 16974.915125424355, 17150.914245428787, 17160.22778974946, 16032.679346413064, 16644.251008704607, 16754.329826806912, 16772.245248963794, 16822.24299906504, 744.2222877093424, 900.7838118851478, 915.7390143809016, 922.294444749767, 922.3635691372965, 887.3699673232013, 892.2415946445534, 905.1898550797033, 908.8500397434416, 910.8588168833842, 943.1276069635594, 947.947862867542, 953.0612346838393, 954.1555723184962, 955.159459675488, 929.251952178496, 933.9906600933972, 938.4134915677702, 941.2140862379921, 942.222666300305, 687.5840116729356, 689.9275576064491, 691.2603514239765, 691.8443350246155, 692.3146085375492, 16980.74528882068, 16982.66034679312, 17097.230624621923, 17098.401555945482, 17106.401275955282, 16851.91574042122, 17098.059606721625, 17215.741763873426, 17439.825601744073, 17608.471745847644, 13168.86831131686, 13467.595972120755, 13478.595642130751, 13519.256440895746, 14089.43642254319, 4093.3246014407587, 4095.0581366285624, 4134.855280065213, 4213.47331583552, 4249.0, 89390.0, 89447.0, 89626.5518672409, 89934.0, 94788.99999999932, 13158.671033224055, 13321.333933303296, 13450.596482105553, 13594.524191653245, 13612.183269003892, 25361.239162825146, 25371.746282537308, 25386.365340866625, 25586.616200757002, 25695.743042569335, 9498.810023799486, 9513.286503512265, 9729.70810875675, 9849.901500985041, 9924.553395097239, 5197.922031169535, 5252.527272545489, 5370.221317908881, 5378.596605254622, 5493.7802487900835, 3887.2031233597045, 3925.685945124383, 3941.960580394217, 3988.8603898863403, 3996.7402118862183, 653.8103949854489, 662.5925056090566, 665.9101021362151, 681.6898311268361, 692.2731132311154, 704.7956092733152, 711.9145702515627, 764.9120351159688, 810.7486679129489, 824.2664029014182, 9588.808223835484, 10481.685549433529, 10520.736981575368, 10617.362958222377, 11305.321680699184, 700.7057036044873, 761.9923800761921, 778.3734094054228, 811.1077814404226, 811.6834434570522, 5269.104252277122, 5270.393904701008, 5283.1546952487415, 5283.682979021271, 5372.973135134299, 126350.0, 131233.0, 131537.0, 132098.0, 133712.0, 97895.0, 102689.0, 103538.48230758798, 105410.0, 107025.0, 89149.55425222962, 90613.54693226492, 91990.0, 93158.53420732853, 94382.99999999863, 10235.692929212135, 10359.482025898524, 10469.31949423285, 10543.94728026355, 10562.894371056498, 56260.71869640626, 56343.71828140833, 57697.4230257692, 59258.0, 59808.70095649494, 76395.0, 81951.0, 86176.0, 88136.559317203, 88206.0, 13472.663183420493, 13805.24071176095, 13829.654258643614, 13836.930815345859, 13915.652108696959, 14994.850051499781, 15218.923905380403, 15231.771523427378, 15344.30950607203, 15643.217839108227, 14557.12657240569, 15600.297986590404, 15837.920810395874, 16412.343506260095, 16810.211474935222, 15402.691946161238, 15722.449714259947, 15947.362105516113, 17121.400750973655, 17263.82736172622, 15487.5353739388, 15870.9206453967, 16303.046029781288, 16317.850257388596, 16496.83503165001, 10357.378557286587, 12418.192817466836, 12494.562690305796, 12617.93691031539, 12776.808347874976, 10790.088174816106, 11586.0, 12475.563355282522, 12837.935810320889, 12987.480500779862], "value": 8438.64949946112, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1502.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 106.0, 111.0, 112.0, 112.0, 113.0, 112.0, 110.0, 112.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 142.0, 166.0, 119.0, 142.0, 157.0, 119.0, 179.0, 118.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 163.0, 163.0, 161.0, 162.0, 163.0, 165.0, 167.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 173.0, 75.0, 76.0, 74.0, 76.0, 170.0, 76.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 145.0, 84.0, 83.0, 83.0, 144.0, 83.0, 83.0, 144.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 121.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 149.0, 147.0, 148.0, 147.0, 147.0, 147.0, 151.0, 152.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 98.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 49.0, 50.0, 53.0, 50.0, 52.0, 51.0, 51.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 62.0, 60.0, 58.0, 59.0, 60.0, 61.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 72.0, 73.0, 73.0, 76.0, 73.0, 78.0, 74.0, 71.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 124.0, 116.0, 129.0, 120.0, 130.0, 119.0, 154.0, 128.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 153.0, 156.0, 154.0, 152.0, 151.0, 156.0, 154.0, 155.0, 151.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 69.0, 61.0, 61.0, 68.0, 66.0, 60.0, 63.0, 61.0, 64.0], "value": 65.0, "unit": "score"}]}]} 14:42:40 INFO - Return code: 0 14:42:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:42:40 ERROR - # TBPL SUCCESS # 14:42:40 INFO - Running post-action listener: _resource_record_post_action 14:42:40 INFO - [mozharness: 2016-08-22 21:42:40.588581Z] Finished run-tests step (success) 14:42:40 INFO - Running post-run listener: _resource_record_post_run 14:42:40 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 274432; Write bytes: 330625024; Read time: 76; Write time: 187900 14:42:40 INFO - TinderboxPrint: CPU usage
13.2% 14:42:40 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 76 14:42:40 INFO - TinderboxPrint: I/O write bytes / time
330,625,024 / 187,900 14:42:40 INFO - TinderboxPrint: CPU idle
8,217.0 (86.8%) 14:42:40 INFO - TinderboxPrint: CPU user
1,200.9 (12.7%) 14:42:40 INFO - TinderboxPrint: Swap in / out
0 / 0 14:42:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 79515648; Read time: 16; Write time: 65384 14:42:40 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 266240; Write bytes: 251109376; Read time: 48; Write time: 122516 14:42:41 INFO - Running post-run listener: _upload_blobber_files 14:42:41 INFO - Blob upload gear active. 14:42:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:42:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:42:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:42:41 INFO - (blobuploader) - INFO - Open directory for files ... 14:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:42:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:42 INFO - (blobuploader) - INFO - Done attempting. 14:42:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:42:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:42:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:48 INFO - (blobuploader) - INFO - Done attempting. 14:42:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:42:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:42:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:42:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:42:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:42:49 INFO - (blobuploader) - INFO - Done attempting. 14:42:49 INFO - (blobuploader) - INFO - Iteration through files over. 14:42:49 INFO - Return code: 0 14:42:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:42:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:42:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fe402ffeb2be21acd8a70386c72ea0b25290be97cede1fb6e7bcf685f339188ad30f5ddd665af2ffc6d7686834fef5f2112df6d7d80abfbf31d4fad39ac5082", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e993a8676ead4e322bb7a5220905db067b7c135169528271ff53a5a8cfe3afc3715208747a9bd91f4fc3bd748f43b1c75c8bb45eda71d42037e72a3ce955c702", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7793ec37a466620812aff520df230afb54a80151b6a5d1107665000dfcd5d4f7ba24bf6cfd3d031478d6a08ac5c36975eae209f56caa744deca42b9d2bc8102c"} 14:42:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:42:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:42:49 INFO - Contents: 14:42:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fe402ffeb2be21acd8a70386c72ea0b25290be97cede1fb6e7bcf685f339188ad30f5ddd665af2ffc6d7686834fef5f2112df6d7d80abfbf31d4fad39ac5082", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e993a8676ead4e322bb7a5220905db067b7c135169528271ff53a5a8cfe3afc3715208747a9bd91f4fc3bd748f43b1c75c8bb45eda71d42037e72a3ce955c702", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7793ec37a466620812aff520df230afb54a80151b6a5d1107665000dfcd5d4f7ba24bf6cfd3d031478d6a08ac5c36975eae209f56caa744deca42b9d2bc8102c"} 14:42:49 INFO - Running post-run listener: copy_logs_to_upload_dir 14:42:49 INFO - Copying logs to upload dir... 14:42:49 INFO - mkdir: /builds/slave/test/build/upload/logs 14:42:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.470699 ========= master_lag: 0.92 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-22 14:42:49.814152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:42:49.821432) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fe402ffeb2be21acd8a70386c72ea0b25290be97cede1fb6e7bcf685f339188ad30f5ddd665af2ffc6d7686834fef5f2112df6d7d80abfbf31d4fad39ac5082", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e993a8676ead4e322bb7a5220905db067b7c135169528271ff53a5a8cfe3afc3715208747a9bd91f4fc3bd748f43b1c75c8bb45eda71d42037e72a3ce955c702", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7793ec37a466620812aff520df230afb54a80151b6a5d1107665000dfcd5d4f7ba24bf6cfd3d031478d6a08ac5c36975eae209f56caa744deca42b9d2bc8102c"} build_url:https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/HTmvBOLaT5OUEtrBfWYUAw/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8fe402ffeb2be21acd8a70386c72ea0b25290be97cede1fb6e7bcf685f339188ad30f5ddd665af2ffc6d7686834fef5f2112df6d7d80abfbf31d4fad39ac5082", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e993a8676ead4e322bb7a5220905db067b7c135169528271ff53a5a8cfe3afc3715208747a9bd91f4fc3bd748f43b1c75c8bb45eda71d42037e72a3ce955c702", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7793ec37a466620812aff520df230afb54a80151b6a5d1107665000dfcd5d4f7ba24bf6cfd3d031478d6a08ac5c36975eae209f56caa744deca42b9d2bc8102c"}' ========= master_lag: 0.29 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:42:50.125822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:42:50.128514) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1471900488.722340-1992654525 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:42:50.145095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:42:50.145436) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:42:50.145730) ========= ========= Total master_lag: 1.29 =========