builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1474973738.94 results: success (0) buildid: 20160927005849 builduid: a4afe341f3294956a3faacc723f055da revision: 2704e305065955cf0f5e448202fa3df1014c0bae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:38.943208) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:38.944196) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:38.944895) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:38.963133) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:38.963826) ========= 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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-27 03:55:39-- 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% 70.8M=0s 2016-09-27 03:55:39 (70.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.155325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.156073) ========= 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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019426 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.209842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.210662) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2704e305065955cf0f5e448202fa3df1014c0bae --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-beta --rev 2704e305065955cf0f5e448202fa3df1014c0bae --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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-27 03:55:39,850 truncating revision to first 12 chars 2016-09-27 03:55:39,850 Setting DEBUG logging. 2016-09-27 03:55:39,851 attempt 1/10 2016-09-27 03:55:39,851 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2704e3050659?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 03:55:40,158 unpacking tar archive at: mozilla-beta-2704e3050659/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623285 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.849093) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.849478) ========= script_repo_revision: 2704e305065955cf0f5e448202fa3df1014c0bae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.849918) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.850246) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 03:55:39.862383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-27 03:55:39.863164) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:55:40 INFO - MultiFileLogger online at 20160927 03:55:40 in /builds/slave/test 03:55:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 03:55:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:55:40 INFO - {'append_to_log': False, 03:55:40 INFO - 'base_work_dir': '/builds/slave/test', 03:55:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:55:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:55:40 INFO - 'branch': 'Mozilla-Beta', 03:55:40 INFO - 'buildbot_json_path': 'buildprops.json', 03:55:40 INFO - 'config_files': ('talos/linux_config.py',), 03:55:40 INFO - 'default_actions': ('clobber', 03:55:40 INFO - 'read-buildbot-config', 03:55:40 INFO - 'download-and-extract', 03:55:40 INFO - 'populate-webroot', 03:55:40 INFO - 'create-virtualenv', 03:55:40 INFO - 'install', 03:55:40 INFO - 'run-tests'), 03:55:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:55:40 INFO - 'download_minidump_stackwalk': True, 03:55:40 INFO - 'download_symbols': 'ondemand', 03:55:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:55:40 INFO - 'tooltool.py': '/tools/tooltool.py', 03:55:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:55:40 INFO - '/tools/misc-python/virtualenv.py')}, 03:55:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:55:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:55:40 INFO - 'installer_path': 'installer.exe', 03:55:40 INFO - 'log_level': 'info', 03:55:40 INFO - 'log_name': 'talos', 03:55:40 INFO - 'log_to_console': True, 03:55:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:55:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:55:40 INFO - 'opt_config_files': (), 03:55:40 INFO - 'pip_index': False, 03:55:40 INFO - 'sps_profile': False, 03:55:40 INFO - 'sps_profile_interval': 0, 03:55:40 INFO - 'suite': 'dromaeojs', 03:55:40 INFO - 'system_bits': '32', 03:55:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:55:40 INFO - 'title': 'talos-linux64-ix-021', 03:55:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:55:40 INFO - 'use_talos_json': True, 03:55:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:55:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:55:40 INFO - 'work_dir': 'build'} 03:55:40 INFO - [mozharness: 2016-09-27 10:55:40.605328Z] Running clobber step. 03:55:40 INFO - Running pre-action listener: _resource_record_pre_action 03:55:40 INFO - Running main action method: clobber 03:55:40 INFO - rmtree: /builds/slave/test/build 03:55:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:55:41 INFO - Running post-action listener: _resource_record_post_action 03:55:41 INFO - [mozharness: 2016-09-27 10:55:41.633370Z] Finished clobber step (success) 03:55:41 INFO - [mozharness: 2016-09-27 10:55:41.633471Z] Running read-buildbot-config step. 03:55:41 INFO - Running pre-action listener: _resource_record_pre_action 03:55:41 INFO - Running main action method: read_buildbot_config 03:55:41 INFO - Using buildbot properties: 03:55:41 INFO - { 03:55:41 INFO - "project": "", 03:55:41 INFO - "product": "firefox", 03:55:41 INFO - "script_repo_revision": "production", 03:55:41 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:55:41 INFO - "repository": "", 03:55:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 03:55:41 INFO - "buildid": "20160927005849", 03:55:41 INFO - "pgo_build": "True", 03:55:41 INFO - "basedir": "/builds/slave/test", 03:55:41 INFO - "buildnumber": 173, 03:55:41 INFO - "slavename": "talos-linux64-ix-021", 03:55:41 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:55:41 INFO - "platform": "ubuntu64_hw", 03:55:41 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 03:55:41 INFO - "branch": "mozilla-beta", 03:55:41 INFO - "repo_path": "releases/mozilla-beta", 03:55:41 INFO - "revision": "2704e305065955cf0f5e448202fa3df1014c0bae", 03:55:41 INFO - "stage_platform": "linux64-pgo", 03:55:41 INFO - "builduid": "a4afe341f3294956a3faacc723f055da", 03:55:41 INFO - "slavebuilddir": "test" 03:55:41 INFO - } 03:55:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 03:55:41 INFO - Running post-action listener: _resource_record_post_action 03:55:41 INFO - [mozharness: 2016-09-27 10:55:41.635930Z] Finished read-buildbot-config step (success) 03:55:41 INFO - [mozharness: 2016-09-27 10:55:41.636020Z] Running download-and-extract step. 03:55:41 INFO - Running pre-action listener: _resource_record_pre_action 03:55:41 INFO - Running main action method: download_and_extract 03:55:41 INFO - mkdir: /builds/slave/test/build/tests 03:55:41 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')]} 03:55:41 INFO - https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:55:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:55:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:55:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:55:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:55:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:55:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:55:41 INFO - retry: Failed, sleeping 30 seconds before retrying 03:56:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:56:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:56:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:56:11 INFO - retry: Failed, sleeping 60 seconds before retrying 03:57:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:57:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:57:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:57:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 03:57:11 INFO - Caught exception: HTTP Error 404: Not Found 03:57:11 INFO - Caught exception: HTTP Error 404: Not Found 03:57:11 INFO - Caught exception: HTTP Error 404: Not Found 03:57:11 INFO - trying https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:57:11 INFO - Downloading https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:57:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:57:12 INFO - Downloaded 1327 bytes. 03:57:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 03:57:12 INFO - Using the following test package requirements: 03:57:12 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 03:57:12 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 03:57:12 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 03:57:12 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'jsshell-linux-x86_64.zip'], 03:57:12 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 03:57:12 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 03:57:12 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 03:57:12 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 03:57:12 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 03:57:12 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 03:57:12 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 03:57:12 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:57:12 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')]} 03:57:12 INFO - https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:57:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:57:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:57:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:57:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:57:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:57:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:57:12 INFO - retry: Failed, sleeping 30 seconds before retrying 03:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:57:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:57:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:57:42 INFO - retry: Failed, sleeping 60 seconds before retrying 03:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:58:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:58:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 03:58:42 INFO - Caught exception: HTTP Error 404: Not Found 03:58:42 INFO - Caught exception: HTTP Error 404: Not Found 03:58:42 INFO - Caught exception: HTTP Error 404: Not Found 03:58:42 INFO - trying https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:42 INFO - Downloading https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:58:43 INFO - Downloaded 21772399 bytes. 03:58:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:58:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:58:44 INFO - Return code: 0 03:58:44 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:58:44 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')]} 03:58:44 INFO - https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:58:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:58:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:58:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:58:44 INFO - retry: Failed, sleeping 30 seconds before retrying 03:59:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:59:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 03:59:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:59:14 INFO - retry: Failed, sleeping 60 seconds before retrying 04:00:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:00:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 04:00:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:00:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 04:00:14 INFO - Caught exception: HTTP Error 404: Not Found 04:00:14 INFO - Caught exception: HTTP Error 404: Not Found 04:00:14 INFO - Caught exception: HTTP Error 404: Not Found 04:00:14 INFO - trying https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 04:00:14 INFO - Downloading https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 04:00:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:00:15 INFO - Downloaded 21772399 bytes. 04:00:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:00:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:00:16 INFO - Return code: 0 04:00:16 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')]} 04:00:16 INFO - https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:00:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:00:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:00:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:00:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:00:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:00:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:00:16 INFO - retry: Failed, sleeping 30 seconds before retrying 04:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:00:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:00:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:00:46 INFO - retry: Failed, sleeping 60 seconds before retrying 04:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 04:01:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:01:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:01:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 04:01:46 INFO - Caught exception: HTTP Error 404: Not Found 04:01:46 INFO - Caught exception: HTTP Error 404: Not Found 04:01:46 INFO - Caught exception: HTTP Error 404: Not Found 04:01:46 INFO - trying https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:01:46 INFO - Downloading https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 04:01:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:01:47 INFO - Downloaded 14413740 bytes. 04:01:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:01:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:01:47 INFO - Return code: 0 04:01:47 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')]} 04:01:47 INFO - https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:01:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:01:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:01:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:01:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:01:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:01:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:01:47 INFO - retry: Failed, sleeping 30 seconds before retrying 04:02:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:02:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:02:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:02:18 INFO - retry: Failed, sleeping 60 seconds before retrying 04:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:03:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:03:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:03:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 04:03:18 INFO - Caught exception: HTTP Error 404: Not Found 04:03:18 INFO - Caught exception: HTTP Error 404: Not Found 04:03:18 INFO - Caught exception: HTTP Error 404: Not Found 04:03:18 INFO - trying https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:03:18 INFO - Downloading https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:03:19 INFO - Downloaded 56786470 bytes. 04:03:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:03:19 INFO - mkdir: /builds/slave/test/properties 04:03:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:03:19 INFO - Writing to file /builds/slave/test/properties/build_url 04:03:19 INFO - Contents: 04:03:19 INFO - build_url:https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 04:03:19 INFO - Running post-action listener: _resource_record_post_action 04:03:19 INFO - Running post-action listener: set_extra_try_arguments 04:03:19 INFO - [mozharness: 2016-09-27 11:03:19.870486Z] Finished download-and-extract step (success) 04:03:19 INFO - [mozharness: 2016-09-27 11:03:19.870718Z] Running populate-webroot step. 04:03:19 INFO - Running pre-action listener: _resource_record_pre_action 04:03:19 INFO - Running main action method: populate_webroot 04:03:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:03:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:03:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:03:19 INFO - '--tppagecycles', 04:03:19 INFO - '7'], 04:03:19 INFO - 'tests': ['tsvgm']}}, 04:03:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:03:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:03:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:03:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:03:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['damp', 'tps']}, 04:03:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'tests': ['damp', 'tps']}, 04:03:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['dromaeo_dom']}, 04:03:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:03:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['basic_compositor_video']}, 04:03:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:03:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'other-e10s': {'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['a11yr', 04:03:19 INFO - 'ts_paint', 04:03:19 INFO - 'tpaint', 04:03:19 INFO - 'sessionrestore', 04:03:19 INFO - 'sessionrestore_no_auto_restore', 04:03:19 INFO - 'tabpaint']}, 04:03:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['tsvgx', 04:03:19 INFO - 'tsvgr_opacity', 04:03:19 INFO - 'tart', 04:03:19 INFO - 'tscrollx', 04:03:19 INFO - 'cart']}, 04:03:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:03:19 INFO - 'tsvgr_opacity', 04:03:19 INFO - 'tart', 04:03:19 INFO - 'tscrollx', 04:03:19 INFO - 'cart']}, 04:03:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'talos_options': ['--disable-e10s'], 04:03:19 INFO - 'tests': ['tp5o']}, 04:03:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'tests': ['tp5o']}, 04:03:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'talos_options': ['--disable-e10s', 04:03:19 INFO - '--xperf_path', 04:03:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:19 INFO - 'tests': ['tp5n']}, 04:03:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:03:19 INFO - 'talos_options': ['--xperf_path', 04:03:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:19 INFO - 'tests': ['tp5n']}}, 04:03:19 INFO - 'talos.zip': {'path': '', 04:03:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:03:19 INFO - Running post-action listener: _resource_record_post_action 04:03:19 INFO - [mozharness: 2016-09-27 11:03:19.890759Z] Finished populate-webroot step (success) 04:03:19 INFO - [mozharness: 2016-09-27 11:03:19.890849Z] Running create-virtualenv step. 04:03:19 INFO - Running pre-action listener: _resource_record_pre_action 04:03:19 INFO - Running main action method: create_virtualenv 04:03:19 INFO - Creating virtualenv /builds/slave/test/build/venv 04:03:19 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 04:03:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:03:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:03:19 INFO - Using real prefix '/usr' 04:03:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:03:20 INFO - Installing distribute.............................................................................................................................................................................................done. 04:03:22 INFO - Installing pip.................done. 04:03:22 INFO - Return code: 0 04:03:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:03: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')]} 04:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03: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')]} 04:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:22 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:22 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 04:03:22 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 04:03:22 INFO - Using env: {'DISPLAY': ':0', 04:03:22 INFO - 'HOME': '/home/cltbld', 04:03:22 INFO - 'LANG': 'en_US.UTF-8', 04:03:22 INFO - 'LANGUAGE': 'en_US:en', 04:03:22 INFO - 'LOGNAME': 'cltbld', 04:03:22 INFO - 'MAIL': '/var/mail/cltbld', 04:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:22 INFO - 'MOZ_NO_REMOTE': '1', 04:03:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:22 INFO - 'NO_EM_RESTART': '1', 04:03:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:22 INFO - 'PWD': '/builds/slave/test', 04:03:22 INFO - 'SHELL': '/bin/bash', 04:03:22 INFO - 'SHLVL': '1', 04:03:22 INFO - 'TERM': 'linux', 04:03:22 INFO - 'TMOUT': '86400', 04:03:22 INFO - 'USER': 'cltbld', 04:03:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:22 INFO - '_': '/tools/buildbot/bin/python'} 04:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:22 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 04:03:22 INFO - Cleaning up... 04:03:22 INFO - Return code: 0 04:03:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:03: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')]} 04:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03: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')]} 04:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:22 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:22 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 04:03:22 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 04:03:22 INFO - Using env: {'DISPLAY': ':0', 04:03:22 INFO - 'HOME': '/home/cltbld', 04:03:22 INFO - 'LANG': 'en_US.UTF-8', 04:03:22 INFO - 'LANGUAGE': 'en_US:en', 04:03:22 INFO - 'LOGNAME': 'cltbld', 04:03:22 INFO - 'MAIL': '/var/mail/cltbld', 04:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:22 INFO - 'MOZ_NO_REMOTE': '1', 04:03:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:22 INFO - 'NO_EM_RESTART': '1', 04:03:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:22 INFO - 'PWD': '/builds/slave/test', 04:03:22 INFO - 'SHELL': '/bin/bash', 04:03:22 INFO - 'SHLVL': '1', 04:03:22 INFO - 'TERM': 'linux', 04:03:22 INFO - 'TMOUT': '86400', 04:03:22 INFO - 'USER': 'cltbld', 04:03:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:22 INFO - '_': '/tools/buildbot/bin/python'} 04:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:22 INFO - Downloading/unpacking psutil>=3.1.1 04:03:22 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 04:03:22 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 04:03:22 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 04:03:22 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 04:03:22 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 04:03:22 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 04:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:03:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:24 INFO - Installing collected packages: psutil 04:03:24 INFO - Running setup.py install for psutil 04:03:24 INFO - building 'psutil._psutil_linux' extension 04:03:24 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 04:03:25 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 04:03:25 INFO - building 'psutil._psutil_posix' extension 04:03:25 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 04:03:25 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 04:03:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:25 INFO - Successfully installed psutil 04:03:25 INFO - Cleaning up... 04:03:25 INFO - Return code: 0 04:03:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:03:25 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')]} 04:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:25 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')]} 04:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:25 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:25 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 04:03:25 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 04:03:25 INFO - Using env: {'DISPLAY': ':0', 04:03:25 INFO - 'HOME': '/home/cltbld', 04:03:25 INFO - 'LANG': 'en_US.UTF-8', 04:03:25 INFO - 'LANGUAGE': 'en_US:en', 04:03:25 INFO - 'LOGNAME': 'cltbld', 04:03:25 INFO - 'MAIL': '/var/mail/cltbld', 04:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:25 INFO - 'MOZ_NO_REMOTE': '1', 04:03:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:25 INFO - 'NO_EM_RESTART': '1', 04:03:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:25 INFO - 'PWD': '/builds/slave/test', 04:03:25 INFO - 'SHELL': '/bin/bash', 04:03:25 INFO - 'SHLVL': '1', 04:03:25 INFO - 'TERM': 'linux', 04:03:25 INFO - 'TMOUT': '86400', 04:03:25 INFO - 'USER': 'cltbld', 04:03:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:25 INFO - '_': '/tools/buildbot/bin/python'} 04:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:03:25 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 04:03:25 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 04:03:25 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 04:03:25 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 04:03:25 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 04:03:25 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 04:03:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:03:27 INFO - Installing collected packages: mozsystemmonitor 04:03:27 INFO - Running setup.py install for mozsystemmonitor 04:03:27 INFO - Successfully installed mozsystemmonitor 04:03:27 INFO - Cleaning up... 04:03:27 INFO - Return code: 0 04:03:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:03:27 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')]} 04:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:27 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')]} 04:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:27 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:27 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 04:03:27 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 04:03:27 INFO - Using env: {'DISPLAY': ':0', 04:03:27 INFO - 'HOME': '/home/cltbld', 04:03:27 INFO - 'LANG': 'en_US.UTF-8', 04:03:27 INFO - 'LANGUAGE': 'en_US:en', 04:03:27 INFO - 'LOGNAME': 'cltbld', 04:03:27 INFO - 'MAIL': '/var/mail/cltbld', 04:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:27 INFO - 'MOZ_NO_REMOTE': '1', 04:03:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:27 INFO - 'NO_EM_RESTART': '1', 04:03:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:27 INFO - 'PWD': '/builds/slave/test', 04:03:27 INFO - 'SHELL': '/bin/bash', 04:03:27 INFO - 'SHLVL': '1', 04:03:27 INFO - 'TERM': 'linux', 04:03:27 INFO - 'TMOUT': '86400', 04:03:27 INFO - 'USER': 'cltbld', 04:03:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:27 INFO - '_': '/tools/buildbot/bin/python'} 04:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:27 INFO - Downloading/unpacking jsonschema==2.5.1 04:03:27 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 04:03:27 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 04:03:27 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 04:03:27 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 04:03:27 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 04:03:27 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 04:03:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:03:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:03:29 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 04:03:29 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 04:03:29 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 04:03:29 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 04:03:29 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 04:03:29 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 04:03:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:03:30 INFO - warning: no files found matching '*.txt' 04:03:30 INFO - no previously-included directories found matching 'build' 04:03:30 INFO - no previously-included directories found matching 'dist' 04:03:30 INFO - no previously-included directories found matching '.git*' 04:03:30 INFO - Installing collected packages: jsonschema, functools32 04:03:30 INFO - Running setup.py install for functools32 04:03:30 INFO - warning: no files found matching '*.txt' 04:03:30 INFO - no previously-included directories found matching 'build' 04:03:30 INFO - no previously-included directories found matching 'dist' 04:03:30 INFO - no previously-included directories found matching '.git*' 04:03:30 INFO - Successfully installed jsonschema functools32 04:03:30 INFO - Cleaning up... 04:03:30 INFO - Return code: 0 04:03:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:03:30 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')]} 04:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:30 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')]} 04:03:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:30 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:30 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 04:03:30 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 04:03:30 INFO - Using env: {'DISPLAY': ':0', 04:03:30 INFO - 'HOME': '/home/cltbld', 04:03:30 INFO - 'LANG': 'en_US.UTF-8', 04:03:30 INFO - 'LANGUAGE': 'en_US:en', 04:03:30 INFO - 'LOGNAME': 'cltbld', 04:03:30 INFO - 'MAIL': '/var/mail/cltbld', 04:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:30 INFO - 'MOZ_NO_REMOTE': '1', 04:03:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:30 INFO - 'NO_EM_RESTART': '1', 04:03:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:30 INFO - 'PWD': '/builds/slave/test', 04:03:30 INFO - 'SHELL': '/bin/bash', 04:03:30 INFO - 'SHLVL': '1', 04:03:30 INFO - 'TERM': 'linux', 04:03:30 INFO - 'TMOUT': '86400', 04:03:30 INFO - 'USER': 'cltbld', 04:03:30 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:30 INFO - '_': '/tools/buildbot/bin/python'} 04:03:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:03:30 INFO - Cleaning up... 04:03:30 INFO - Return code: 0 04:03:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:03:30 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')]} 04:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:30 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')]} 04:03:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:30 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:30 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 04:03:30 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 04:03:30 INFO - Using env: {'DISPLAY': ':0', 04:03:30 INFO - 'HOME': '/home/cltbld', 04:03:30 INFO - 'LANG': 'en_US.UTF-8', 04:03:30 INFO - 'LANGUAGE': 'en_US:en', 04:03:30 INFO - 'LOGNAME': 'cltbld', 04:03:30 INFO - 'MAIL': '/var/mail/cltbld', 04:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:30 INFO - 'MOZ_NO_REMOTE': '1', 04:03:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:30 INFO - 'NO_EM_RESTART': '1', 04:03:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:30 INFO - 'PWD': '/builds/slave/test', 04:03:30 INFO - 'SHELL': '/bin/bash', 04:03:30 INFO - 'SHLVL': '1', 04:03:30 INFO - 'TERM': 'linux', 04:03:30 INFO - 'TMOUT': '86400', 04:03:30 INFO - 'USER': 'cltbld', 04:03:30 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:30 INFO - '_': '/tools/buildbot/bin/python'} 04:03:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:30 INFO - Downloading/unpacking blobuploader==1.2.4 04:03:30 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 04:03:30 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 04:03:30 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 04:03:30 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 04:03:30 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 04:03:30 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 04:03:32 INFO - Downloading blobuploader-1.2.4.tar.gz 04:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:03:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:03:32 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 04:03:32 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 04:03:32 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 04:03:32 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 04:03:32 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 04:03:32 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 04:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:03:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:03: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 04:03: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 04:03: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 04:03: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 04:03: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 04:03: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 04:03:33 INFO - Downloading docopt-0.6.1.tar.gz 04:03:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:03:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:03:33 INFO - Running setup.py install for blobuploader 04:03:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:03:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:03:33 INFO - Running setup.py install for requests 04:03:33 INFO - Running setup.py install for docopt 04:03:33 INFO - Successfully installed blobuploader requests docopt 04:03:33 INFO - Cleaning up... 04:03:34 INFO - Return code: 0 04:03:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:03:34 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')]} 04:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:34 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')]} 04:03:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:34 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:34 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 04:03:34 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 04:03:34 INFO - Using env: {'DISPLAY': ':0', 04:03:34 INFO - 'HOME': '/home/cltbld', 04:03:34 INFO - 'LANG': 'en_US.UTF-8', 04:03:34 INFO - 'LANGUAGE': 'en_US:en', 04:03:34 INFO - 'LOGNAME': 'cltbld', 04:03:34 INFO - 'MAIL': '/var/mail/cltbld', 04:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:34 INFO - 'MOZ_NO_REMOTE': '1', 04:03:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:34 INFO - 'NO_EM_RESTART': '1', 04:03:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:34 INFO - 'PWD': '/builds/slave/test', 04:03:34 INFO - 'SHELL': '/bin/bash', 04:03:34 INFO - 'SHLVL': '1', 04:03:34 INFO - 'TERM': 'linux', 04:03:34 INFO - 'TMOUT': '86400', 04:03:34 INFO - 'USER': 'cltbld', 04:03:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:34 INFO - '_': '/tools/buildbot/bin/python'} 04:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:03:34 INFO - Running setup.py (path:/tmp/pip-wGaQG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:03:34 INFO - Running setup.py (path:/tmp/pip-fkA6jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:03:34 INFO - Running setup.py (path:/tmp/pip-qU3mZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:03:34 INFO - Running setup.py (path:/tmp/pip-GUByPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:03:34 INFO - Running setup.py (path:/tmp/pip-4YrqHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:03:34 INFO - Running setup.py (path:/tmp/pip-PFR0jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:03:34 INFO - Running setup.py (path:/tmp/pip-e7R_Sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:03:34 INFO - Running setup.py (path:/tmp/pip-GNAf0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:03:34 INFO - Running setup.py (path:/tmp/pip-ralELk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:03:34 INFO - Running setup.py (path:/tmp/pip-Vvj0Aj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:03:34 INFO - Running setup.py (path:/tmp/pip-OVppq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:03:35 INFO - Running setup.py (path:/tmp/pip-Ka80_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:03:35 INFO - Running setup.py (path:/tmp/pip-MlyA6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:03:35 INFO - Running setup.py (path:/tmp/pip-zvVVwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:35 INFO - Running setup.py (path:/tmp/pip-PM2XNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:03:35 INFO - Running setup.py (path:/tmp/pip-eJcBBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:03:35 INFO - Running setup.py (path:/tmp/pip-0kcJij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:03:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:03:35 INFO - Running setup.py install for manifestparser 04:03:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:03:35 INFO - Running setup.py install for mozcrash 04:03:35 INFO - Running setup.py install for mozdebug 04:03:35 INFO - Running setup.py install for mozdevice 04:03:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:03:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:03:35 INFO - Running setup.py install for mozfile 04:03:36 INFO - Running setup.py install for mozhttpd 04:03:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozinfo 04:03:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozInstall 04:03:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozleak 04:03:36 INFO - Running setup.py install for mozlog 04:03:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for moznetwork 04:03:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozprocess 04:03:36 INFO - Running setup.py install for mozprofile 04:03:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozrunner 04:03:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:03:36 INFO - Running setup.py install for mozscreenshot 04:03:37 INFO - Running setup.py install for moztest 04:03:37 INFO - Running setup.py install for mozversion 04:03:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:03:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:03:37 INFO - Cleaning up... 04:03:37 INFO - Return code: 0 04:03:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:03:37 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')]} 04:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:37 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')]} 04:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:37 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:37 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 04:03:37 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 04:03:37 INFO - Using env: {'DISPLAY': ':0', 04:03:37 INFO - 'HOME': '/home/cltbld', 04:03:37 INFO - 'LANG': 'en_US.UTF-8', 04:03:37 INFO - 'LANGUAGE': 'en_US:en', 04:03:37 INFO - 'LOGNAME': 'cltbld', 04:03:37 INFO - 'MAIL': '/var/mail/cltbld', 04:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:37 INFO - 'MOZ_NO_REMOTE': '1', 04:03:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:37 INFO - 'NO_EM_RESTART': '1', 04:03:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:37 INFO - 'PWD': '/builds/slave/test', 04:03:37 INFO - 'SHELL': '/bin/bash', 04:03:37 INFO - 'SHLVL': '1', 04:03:37 INFO - 'TERM': 'linux', 04:03:37 INFO - 'TMOUT': '86400', 04:03:37 INFO - 'USER': 'cltbld', 04:03:37 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:37 INFO - '_': '/tools/buildbot/bin/python'} 04:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:03:37 INFO - Running setup.py (path:/tmp/pip-0QGA5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:03:37 INFO - Running setup.py (path:/tmp/pip-mFqn8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:03:37 INFO - Running setup.py (path:/tmp/pip-pruWPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:03:37 INFO - Running setup.py (path:/tmp/pip-QDhFoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:03:37 INFO - Running setup.py (path:/tmp/pip-NPG7eA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:03:37 INFO - Running setup.py (path:/tmp/pip-cOdLvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:03:37 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)) 04:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:03:37 INFO - Running setup.py (path:/tmp/pip-KooEmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:03:38 INFO - Running setup.py (path:/tmp/pip-ND0baR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:03:38 INFO - Running setup.py (path:/tmp/pip-QKZGyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:03:38 INFO - Running setup.py (path:/tmp/pip-SRKqmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:03:38 INFO - Running setup.py (path:/tmp/pip-FIh1w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:03:38 INFO - Running setup.py (path:/tmp/pip-sIQXLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:03:38 INFO - Running setup.py (path:/tmp/pip-3X8cAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:03:38 INFO - Running setup.py (path:/tmp/pip-uyqPIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:38 INFO - Running setup.py (path:/tmp/pip-7PFpxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:03:38 INFO - Running setup.py (path:/tmp/pip-cRHTbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:03:38 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)) 04:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:03:38 INFO - Running setup.py (path:/tmp/pip-Y4DTmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:03:38 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)) 04:03:38 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)) 04:03:38 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)) 04:03:38 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)) 04:03:38 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)) 04:03:38 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)) 04:03:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:03:38 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 04:03:38 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 04:03:38 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 04:03:38 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 04:03:38 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 04:03:38 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 04:03:40 INFO - Downloading blessings-1.6.tar.gz 04:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:03:40 INFO - Installing collected packages: blessings 04:03:40 INFO - Running setup.py install for blessings 04:03:40 INFO - Successfully installed blessings 04:03:40 INFO - Cleaning up... 04:03:40 INFO - Return code: 0 04:03:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:03:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:03:41 INFO - Reading from file tmpfile_stdout 04:03:41 INFO - Current package versions: 04:03:41 INFO - argparse == 1.2.1 04:03:41 INFO - blessings == 1.6 04:03:41 INFO - blobuploader == 1.2.4 04:03:41 INFO - docopt == 0.6.1 04:03:41 INFO - functools32 == 3.2.3-2 04:03:41 INFO - jsonschema == 2.5.1 04:03:41 INFO - manifestparser == 1.1 04:03:41 INFO - mozInstall == 1.12 04:03:41 INFO - mozcrash == 0.17 04:03:41 INFO - mozdebug == 0.1 04:03:41 INFO - mozdevice == 0.48 04:03:41 INFO - mozfile == 1.2 04:03:41 INFO - mozhttpd == 0.7 04:03:41 INFO - mozinfo == 0.9 04:03:41 INFO - mozleak == 0.1 04:03:41 INFO - mozlog == 3.3 04:03:41 INFO - moznetwork == 0.27 04:03:41 INFO - mozprocess == 0.23 04:03:41 INFO - mozprofile == 0.28 04:03:41 INFO - mozrunner == 6.12 04:03:41 INFO - mozscreenshot == 0.1 04:03:41 INFO - mozsystemmonitor == 0.3 04:03:41 INFO - moztest == 0.7 04:03:41 INFO - mozversion == 1.4 04:03:41 INFO - psutil == 3.1.1 04:03:41 INFO - requests == 1.2.3 04:03:41 INFO - wsgiref == 0.1.2 04:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:03:41 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')]} 04:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:41 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')]} 04:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:41 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:41 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 04:03:41 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 04:03:41 INFO - Using env: {'DISPLAY': ':0', 04:03:41 INFO - 'HOME': '/home/cltbld', 04:03:41 INFO - 'LANG': 'en_US.UTF-8', 04:03:41 INFO - 'LANGUAGE': 'en_US:en', 04:03:41 INFO - 'LOGNAME': 'cltbld', 04:03:41 INFO - 'MAIL': '/var/mail/cltbld', 04:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:41 INFO - 'MOZ_NO_REMOTE': '1', 04:03:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:41 INFO - 'NO_EM_RESTART': '1', 04:03:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:41 INFO - 'PWD': '/builds/slave/test', 04:03:41 INFO - 'SHELL': '/bin/bash', 04:03:41 INFO - 'SHLVL': '1', 04:03:41 INFO - 'TERM': 'linux', 04:03:41 INFO - 'TMOUT': '86400', 04:03:41 INFO - 'USER': 'cltbld', 04:03:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:41 INFO - '_': '/tools/buildbot/bin/python'} 04:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 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)) 04:03:41 INFO - Cleaning up... 04:03:41 INFO - Return code: 0 04:03:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:03:41 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')]} 04:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:41 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')]} 04:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:03:41 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 0xcdc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe3f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf9d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, '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': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', '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 04:03:41 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 04:03:41 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 04:03:41 INFO - Using env: {'DISPLAY': ':0', 04:03:41 INFO - 'HOME': '/home/cltbld', 04:03:41 INFO - 'LANG': 'en_US.UTF-8', 04:03:41 INFO - 'LANGUAGE': 'en_US:en', 04:03:41 INFO - 'LOGNAME': 'cltbld', 04:03:41 INFO - 'MAIL': '/var/mail/cltbld', 04:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:41 INFO - 'MOZ_NO_REMOTE': '1', 04:03:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:41 INFO - 'NO_EM_RESTART': '1', 04:03:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:41 INFO - 'PWD': '/builds/slave/test', 04:03:41 INFO - 'SHELL': '/bin/bash', 04:03:41 INFO - 'SHLVL': '1', 04:03:41 INFO - 'TERM': 'linux', 04:03:41 INFO - 'TMOUT': '86400', 04:03:41 INFO - 'USER': 'cltbld', 04:03:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:41 INFO - '_': '/tools/buildbot/bin/python'} 04:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:03:41 INFO - Cleaning up... 04:03:41 INFO - Return code: 0 04:03:41 INFO - Running post-action listener: _resource_record_post_action 04:03:41 INFO - Running post-action listener: _start_resource_monitoring 04:03:41 INFO - Starting resource monitoring. 04:03:41 INFO - [mozharness: 2016-09-27 11:03:41.606089Z] Finished create-virtualenv step (success) 04:03:41 INFO - [mozharness: 2016-09-27 11:03:41.606521Z] Running install step. 04:03:41 INFO - Running pre-action listener: _resource_record_pre_action 04:03:41 INFO - Running main action method: install 04:03:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:03:41 INFO - Reading from file tmpfile_stdout 04:03:41 INFO - Detecting whether we're running mozinstall >=1.0... 04:03:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:03:41 INFO - Reading from file tmpfile_stdout 04:03:41 INFO - Output received: 04:03:41 INFO - Usage: mozinstall [options] installer 04:03:41 INFO - Options: 04:03:41 INFO - -h, --help show this help message and exit 04:03:41 INFO - -d DEST, --destination=DEST 04:03:41 INFO - Directory to install application into. [default: 04:03:41 INFO - "/builds/slave/test"] 04:03:41 INFO - --app=APP Application being installed. [default: firefox] 04:03:41 INFO - mkdir: /builds/slave/test/build/application 04:03:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:03:56 INFO - Reading from file tmpfile_stdout 04:03:56 INFO - Output received: 04:03:56 INFO - /builds/slave/test/build/application/firefox/firefox 04:03:56 INFO - Running post-action listener: _resource_record_post_action 04:03:56 INFO - [mozharness: 2016-09-27 11:03:56.080396Z] Finished install step (success) 04:03:56 INFO - [mozharness: 2016-09-27 11:03:56.080676Z] Running run-tests step. 04:03:56 INFO - Running pre-action listener: _resource_record_pre_action 04:03:56 INFO - Running main action method: run_tests 04:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:03:56 INFO - Python 2.7.3 04:03:56 INFO - Return code: 0 04:03:56 INFO - grabbing minidump binary from tooltool 04:03:56 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')]} 04:03:56 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 0xf9b3b0>, '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 0xf9c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf9c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:03:56 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 04:03:56 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 04:03:56 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 04:03:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:03:56 INFO - Return code: 0 04:03:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:03:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:03:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:03:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:03:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:03:56 INFO - Using env: {'DISPLAY': ':0', 04:03:56 INFO - 'HOME': '/home/cltbld', 04:03:56 INFO - 'LANG': 'en_US.UTF-8', 04:03:56 INFO - 'LANGUAGE': 'en_US:en', 04:03:56 INFO - 'LOGNAME': 'cltbld', 04:03:56 INFO - 'MAIL': '/var/mail/cltbld', 04:03:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:03:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:56 INFO - 'MOZ_NO_REMOTE': '1', 04:03:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:03:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:56 INFO - 'NO_EM_RESTART': '1', 04:03:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:56 INFO - 'PWD': '/builds/slave/test', 04:03:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:03:56 INFO - 'SHELL': '/bin/bash', 04:03:56 INFO - 'SHLVL': '1', 04:03:56 INFO - 'TERM': 'linux', 04:03:56 INFO - 'TMOUT': '86400', 04:03:56 INFO - 'USER': 'cltbld', 04:03:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1474973738.816174-648701907', 04:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:56 INFO - '_': '/tools/buildbot/bin/python'} 04:03:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:03:56 INFO - mozversion application_buildid: 20160927005849 04:03:56 INFO - mozversion application_changeset: 2704e305065955cf0f5e448202fa3df1014c0bae 04:03:56 INFO - mozversion application_display_name: Firefox 04:03:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:03:56 INFO - mozversion application_name: Firefox 04:03:56 INFO - mozversion application_remotingname: firefox 04:03:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:03:56 INFO - mozversion application_vendor: Mozilla 04:03:56 INFO - mozversion application_version: 50.0 04:03:56 INFO - mozversion platform_buildid: 20160927005849 04:03:56 INFO - mozversion platform_changeset: 2704e305065955cf0f5e448202fa3df1014c0bae 04:03:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:03:56 INFO - mozversion platform_version: 50.0 04:03:56 INFO - using testdate: 1474974236 04:03:56 INFO - actual date: 1474974236 04:03:56 INFO - starting webserver on 'localhost:51084' 04:03:56 INFO - SUITE-START | Running 2 tests 04:03:56 INFO - TEST-START | dromaeo_css 04:03:56 INFO - operating with platform_type : linux_ 04:03:56 INFO - Initialising browser for dromaeo_css test... 04:03:56 INFO - TEST-INFO | started process 14618 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCUEjGx/profile http://localhost:51084/getInfo.html) 04:04:01 INFO - PROCESS | 14618 | __metrics Screen width/height:1600/1200 04:04:01 INFO - PROCESS | 14618 | colorDepth:24 04:04:01 INFO - PROCESS | 14618 | Browser inner width/height: 1024/697 04:04:01 INFO - PROCESS | 14618 | __metrics 04:04:02 INFO - PROCESS | 14618 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:04:02 INFO - PROCESS | 14618 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:04:03 INFO - TEST-INFO | 14618: exit 0 04:04:03 INFO - Browser initialized. 04:04:03 INFO - Running cycle 1/1 for dromaeo_css test... 04:04:03 INFO - TEST-INFO | started process 14704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCUEjGx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:15 INFO - PROCESS | 14704 | RSS: Main: 143998976 04:04:15 INFO - PROCESS | 14704 | 04:05:05 INFO - PROCESS | 14704 | 1474974305963 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] 04:07:14 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51084/tests/dromaeo/cssquery-ext.html) 04:07:16 INFO - PROCESS | 14704 | RSS: Main: 273997824 04:07:16 INFO - PROCESS | 14704 | 04:10:18 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-ext.html (next: http://localhost:51084/tests/dromaeo/cssquery-jquery.html) 04:10:19 INFO - PROCESS | 14704 | RSS: Main: 235261952 04:10:19 INFO - PROCESS | 14704 | 04:13:20 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51084/tests/dromaeo/cssquery-mootools.html) 04:13:21 INFO - PROCESS | 14704 | RSS: Main: 178159616 04:13:21 INFO - PROCESS | 14704 | 04:16:23 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51084/tests/dromaeo/cssquery-prototype.html) 04:16:24 INFO - PROCESS | 14704 | RSS: Main: 199766016 04:16:24 INFO - PROCESS | 14704 | 04:19:26 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51084/tests/dromaeo/cssquery-yui.html) 04:19:27 INFO - PROCESS | 14704 | RSS: Main: 175849472 04:19:27 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | Cycle 1(1): loaded http://localhost:51084/tests/dromaeo/cssquery-yui.html (next: http://localhost:51084/tests/dromaeo/cssquery-dojo.html) 04:22:29 INFO - PROCESS | 14704 | RSS: Main: 265760768 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | __start_tp_report 04:22:29 INFO - PROCESS | 14704 | _x_x_mozilla_page_load 04:22:29 INFO - PROCESS | 14704 | _x_x_mozilla_page_load_details 04:22:29 INFO - PROCESS | 14704 | |i|pagename|runs| 04:22:29 INFO - PROCESS | 14704 | |0;dojo.html;3217.5495430639717;3219.597550306212;3224.3873664003836;3249;3279.360524697685;5644.181593668921;5695.259616249882;5705.400932902048;5718.685472299022;5718.7998420055255;5708.686022268774;5766.365699773021;5769.451902069297;5786.132080187964;5791.594588378815;5065.569426598743;5151.613628977823;5227.32044834171;5231.450697676746;5258.789648414059;5222.242774797652;5277.366715994086;5279.551238144743;5281.075811732951;5281.603879709018;5187.559057480119;5219.765110570014;5244.082285600024;5248.790048398059;5261.579073674096;5324.201369794523;5328.227407025959;5330.493603107699;5332.066888294533;5341.332333458317;5016.749162541858;5032.547070763649;5061.240813877947;5205.037068142406;5212.374515058179;5604.719764011783;5640.746166422521;5642.4357564243655;5660.462256085665;5697.202391665171;6161.106639537287;6165.414285642857;6168.44483996438;6170.382961703838;6179.87640247197;5943.791967281124;5944.791932282349;6092.766708022538;6201.937980620181;6222.875542489171;5791.797287094973;5807.796727114531;5810.331811841608;5812.418758124154;5814.941850581525;6302.212223472066;6305.558610897193;6306.085617585424;6311.431971122621;6312.400321969406;5799.449052340021;5833.416658334133;5877.47102760745;5879.911801322984;6050.757969681164;6117.480014198843;6117.816465506042;6122.051082082285;6123.142760013601;6128.23662163111;6077.787277445268;6085.5435842312;6088.4824789893355;6094.268687757498;6108.969455152695;5839.153322768175;5879.206307148565;5988.880222395528;6017.969910150508;6052.394760523913;4638.304254361873;4673.672842901033;4677.508861569554;4698.389209402824;4703.717776933395;4125.876223713293;4126.298529250034;4131.669466442738;4142.896427589274;4161.438205842233;5379.6234263601955;5520.696361700145;5573.665580065128;5787.884242315215;5794.536437085023;6098.451139397475;6101.993171126803;6103.816885493442;6127;6138.324784273726;5695.487406133385;5715.371309155989;5715.8856822864145;5718.428157184331;5722.027255366597;5026.220935754964;5039.21892106724;5059.620528460307;5062.164742817467;5076.36545431821;6299.338569450233;6338.271098823694;6339.429451349401;6345.587536810092;6347.333529979378;6107.175531303395;6107.9083813741945;6145.10895920098;6168.290646575701;6174.660393678211;6121.544430001993;6276.592021518587;6311.084892690626;6331.96834015827;6338.651374174465;6116.092308610985;6116.731440059295;6118.840189881278;6120.751898419022;6125.968084364015;6230.127782110418;6235.407636274728;6235.781747638811;6242.843928901632;6254.437100660963;5853.531717462678;5864.882702346014;5868.647881127146;6079.726412311545;6093.9390606093375;5921.851953701192;5954.821355359346;5965.8806823862415;5992.13114098462;6009.519238461;5869.73586188614;5903.350631430453;5909.527237820878;5921.644701317935;5922.141289512911;5953.553483488669;5962.52299816005;5963.850903727268;5977.581569290108;6026.337102918587;5922.881542369215;5932.13983972313;5932.673702946379;5934.109883517507;5959.0763431668165 04:22:29 INFO - PROCESS | 14704 | |1;ext.html;590.9556783241258;634.9428551430371;665.8069159943614;670.3665036540472;679.5718697220751;31354.68645313549;33421.331573368545;33435.33129337412;47195.76402117993;48778.75610621942;48785;49235.50764492354;49271;49277;49360;9265.629374825;9403.529823508832;9405.90594094057;9411.717648470556;9416.858747118798;8541;10956.506957186906;11258.155638327158;11277.605283815068;11648.543932008499;11438.714032149179;11506.539738410453;11526;11531.654050378502;11540.422978851066;11098.112151027939;11149.498272577672;11154.163437742121;11158.553657853676;11164.385958772264;11229.943850280779;11285.322880627107;11291.774164516664;11304.604338848101;11307.943460282646;45471;45551.544484555394;45773;45792;45805.31292030622;3932.134930315326;3934.9016274593077;4077.7145599808027;4340.001799586082;4698.694584851974;35692.64307356919;35904.281914361825;36185.81907090448;36221.27557448836;37689.811550942344;4497.887552811173;4592.770361481913;4603.608693261077;4612.630989520831;4632.930506042412;370138;370714;371854;372070.99999999726;372400;65811.34188658051;66129;66183;66215.66892165605;66261;40804;41648.1670366597;41761.79119104446;43579.56420435818;43583;18745.156467958976;18806.247750090115;18811.059447027594;19002;19039.809601904082;13670.17978921268;15146.848531514765;15302.617434564225;15374.461893833663;15385;20530.178792848117;21118.78881211199;21467.355979320644;21491.785082148977;21512.13951441956;7747.922520774833;7832.334251588679;7918.168592297841;7947.284744373034;7969.561674107864;1084.6637542361893;1113.5267511307825;1121.4785124916953;1142.0920368307216;1190.0955273991829;35764.28471430557;36292.27415451676;36317.27365452676;36323.63676363255;36399.454008189896;1181.7400171962154;1248.9063320251018;1262.8610852806185;1303.4460354349364;1311.8360204974379;18844.717329240073;18955.431337059912;19008.524786880163;19061.237550497826;19065.61868762617;633394;638356;641193;643076;643750;66220;66433.66783166054;66448;66475.33524664788;66597.33402665911;1361.4213959067356;1431.434583339575;1482.6219314074842;1499.8575135362123;1500.8874334424743;34338.656613433544;36203.63796362003;36671.63328366682;36682.61721171207;36792.81603591965;55502.444975549726;56475.71762141163;56544.717276415;56605;56653.716731416076;435700;437656;438263;438427;438871;28958.855205724678;29748.405031899674;30881.536776948815;31034.844825775726;31845.20386990344;53400.73299633477;53696.46303537071;53737.731311343196;54343.72828135834;54353.184702228704;44460.333095002956;45652.315215272465;46348.30477542907;46451.30323045225;46899.765501172275;44495.777521112184;44530.77734611436;46164.307535386324;46561.767191163824;47853.28220076771;45280.09439811251;45619.54380456153;45751.77124114491;45901.770491147334;46120.538794611624;39540.2091958165;41139;41212.09093180115;41426.792866035474;42150;30178.826395614746;30369.69630303757;30503.389932201677;30544.389112217188;31038.37923241568 04:22:29 INFO - PROCESS | 14704 | |2;jquery.html;427.2928303657448;429.6820352938826;430.55222568528734;437.42478640587626;438.8025388575141;19508.31720889769;20849.791502084976;21389;21518.89240553795;21787.455313617167;19709.605807883832;20735.688964665544;20800.375988720323;20892.8955355223;20986.05562749676;5879.647221166743;6133.386661333876;6181.969090154543;6184.474319682832;6191.195144631191;5797.188393624896;6017;6033.034714445689;6034.788782392618;6049.9697501512655;5850.824475265727;6004.849878753044;6016.75932962681;6019.939800602004;6026.306974697922;6028.728707208164;6060.969695151518;6073.51411887048;6090.48230900374;6093.634381937098;5487.2317875497465;5490.752916118744;5497.725113744297;7209.783706488814;7592.278733520307;26996.730032699816;28657.426851463064;28679.71320286791;29006.694698738607;29220.561691574643;2618.2930608735533;2818.0136952066614;3029.2275469755073;3411.232472693624;3611.7291203159866;16656.333746650118;16744;16796.16019199035;16812;16900.746488802677;2203.372038968886;2244.416451722564;2268.88655567221;2272.8181745460324;2290.408166324405;97029.51485242529;99888.99999999927;102997.48501257446;103247.48376258145;103506.48246758794;18850.622987540173;18969.715454268196;18980.43058708241;19269;19497.902510487358;35574.28851423009;35613;35614.643853561654;35701;35937;10774.138068954464;10876.238663293494;10995.448248565304;11073;11352.829707554394;9313.813723725487;9318.30112741547;9350.95324523373;9415.811683766286;9597.424154550748;9418.293627977791;9668.323217374857;9680.177184939219;9706.563204655808;9881.25890558211;3341.966580334214;3377.4933759935716;3621.8732344367822;3668.192997540537;3701.7038636908983;1002.5937749401495;1039.2361614213546;1085.1210519479089;1101.4712937789907;1142.5372724046774;20953.476163096046;21785.564288714137;21814.345569632937;21852.672209916866;22229.444263893532;994.6618149828948;1020.9693709188737;1050.3225419604464;1061.7557961668858;1111.8665760108838;11761.294322340687;12119.575814846614;12152.817707734392;12271.938640306742;12334.81497777534;431024;437502;440674;534878;589413;97474.51262743783;100876;101912;105912;107785;1047.3297089862494;1068.2949253492657;1081.9242653014371;1098.7253186703324;1109.1847492093304;19178;19235.90382048081;19527.414177574694;19704.40886773399;19772.40682779519;46086.769566151954;46182.538174617825;46757.766211168724;46879.53120468752;47030.294545582525;64633.67683161554;64857;65245.67377163084;66372.33627663662;66378.66810665916;8997.415168014059;9047;9177.678781242625;9300.953495232481;9441.386309889835;10204.489775511343;13679.658008549468;14135.293235337986;14475.855241447873;15077.924610377317;12200.75598488043;12403;12431.440585173508;12542.12205145631;12639.304838233986;7072.9646351767915;8832.646694132163;11160.776784464102;12515.249085054926;12976.15654982423;12496.812547811609;12518.749625007267;12723.80914286268;12892;12908.806367904677;9221.953890230507;9371.718848434557;9382.390144640576;9498.667546635834;9621.08599683029;9610.990845961074;12438.751224975631;12563.371831408576;12641.747165056464;12765.170263933187 04:22:29 INFO - PROCESS | 14704 | |3;mootools.html;1464.1507925403266;1558.4623304959784;1578.992105039475;1751.8335758102976;1860.1722233606051;9258.814823703531;9777.413355198683;9788.706338809838;10016.449095299773;10035.59857605695;9874.506274686273;9987.650432234874;10006.299559030871;10014;10021.398716077023;2587.4178309880267;2591.818572699912;2612.725663805302;2615.1370047884247;2615.3330900620317;2524.67179266696;2618.8559629220385;2620.9475810483777;2623.645807815949;2624.868756562174;2482.317362725256;2503.0613519930025;2541.923742287725;2549.375403026261;2558.5906254999204;2487.6268559716004;2507.2979565721625;2508.611165269386;2528.5195812795696;2543.262453888389;2452.31335226137;2467.087177744246;2476.72755996842;2477.4054226985395;2501.774840264367;9698.078682525149;9835.45904975226;9901.950490247502;9904.55429505674;9919.553620087041;5810.360860305363;6047.697615119227;6048.213732214828;6057.3336932937345;6063.848403789896;3609.205974685565;3682.760620559655;3735.514383130203;3762.717796165271;3859.0545316397383;1079.4602698650674;1096.961606343782;1104.7735214281054;1119.4011204005817;1140.5380820767602;13799.172049677052;14014.649633759136;14025.64935876601;14051.016428849985;14086.859131408759;5134.871628209325;5238.266642670029;5385.5960802939935;5439.83680489578;5452.672839629635;11785.351805650771;11849.763004739856;11854.88145118555;11863.46614402354;11867.287962722092;5980.790672326448;5996.910046349309;6017.909731354033;6021.608595441282;6025.8192254232445;4777.235642297216;4967.751612419364;5451.64564303319;5478;5520.1443776214755;4511.639068874548;4531.43357080365;4623.237165867594;4654.371659825948;4665.113628410591;1430.8783753381078;1446.3708286895235;1461.5323096608984;1465.0184376467791;1467.5377256164275;1030.5517100061497;1083.7940791249637;1110.8833572474934;1118.245184500459;1127.7969965406141;6470.54706170573;6786.355296246848;6875.209350924608;6924.480663950224;7036.437085033185;1027.2039169643585;1057.8095942730383;1077.8814330423647;1080.8162612355918;1097.3744965369663;4548.56788605082;4638.2115040443205;4668.976655116703;4739.6558472766355;4783.425988881287;12309.630711078682;12581.37093145339;12636.936815316049;12671.176373535875;12717.682057948441;11506.539738410538;12547.435365408579;12618.62144135659;12620.936895315648;12649.620511384674;8766;9129.315301352291;9204.585793639302;9206;9214.90785092154;6601.10885030534;6753.763618273337;6767.830804229934;6790.151231096113;6840.31596840312;10974.725631858948;11335.659930201777;11362.431878406212;11531.423428828693;11587.942060289644;10406.323588966692;11035.889641103486;11206.159538034517;11725;11747.412629368668;8496.787580310294;8705.129487051245;8778.868316975379;8807.471551706918;8967;9694.951525242328;9755.365901216399;9817.803643927224;9829.213662907094;9829.459379734184;8575.356848236532;8709.608067636846;8751.431156974777;8782.209601135928;8912.55437228149;8445.1977062179;8548.786280343042;8635.568221589021;8635.827283454422;8900.109989001048;7531.3598344141365;7658.502197357154;7668.616569171408;7671.53970761756;7671.769846904602;5663.943360566342;5748.626339287933;5896.64620122794;5904.409559044061;5973.731182096903;5759.596828221985;5849.151872978309;5895.882082358243;5986.161937328865;6025.969870150621 04:22:29 INFO - PROCESS | 14704 | |4;prototype.html;352.6191712950014;356.2607589252301;356.4314917706258;364.872294696856;370.8127395665187;5244.842654720364;5374.247605335257;5450.209719590662;5491.341039075307;5557.305336832896;5362.27609272748;5380.461953804617;5402.459754024596;5404.675719456825;5412.648177868446;4242.745435273878;4255.000074982377;4260.893477663052;4276.743395396271;4278.336857787048;4171;4263.5523270056665;4270.67969902257;4278.443802305712;4292.635126014293;4237.87286381409;4243.511996120455;4243.618074373306;4245.978770106145;4251.532331443539;4218.894527636803;4233.640140588053;4256.808443620013;4262.850800221977;4280.4863416390235;3616.4213725803747;3772.2455508898056;3785.2051069275485;3788.9621103788886;3820.3887378019385;5233.581313494911;5280.947190528084;5289.735513224362;5306.3632364116165;5308.495692909168;4869.610431165534;4877.512248775094;4877.975610121926;4898.142825005645;4910.386201724785;5487.42382049887;5661.320641523043;5663.178839068353;5674.574406919456;5702.3157221133315;4127.422160897477;4133.586641335873;4146.564610715862;4161.854335098287;4172.144710334404;5951.940480595182;5990.371011043865;5992.370801065869;5992.580519363602;6002.499375156211;4089.345704687236;4240.681948853849;4240.851570195028;4249.2563801334645;4264.658827293809;4337.349397590324;4337.609615134653;4339.956600434019;4348.239058164809;4363.818090954523;4914.852554423373;4933.235348520985;4938.506149385033;4959.975200123977;4964.975175124101;5471.945280547223;5679.801206957736;5690.9146362804595;5699.601027928005;5786.987277226553;1384.3424373422545;1445.980583688497;1463.6414078550822;1471.220253265771;1471.9705605887823;993.7465946183677;1003.7590978165188;1022.3763504262394;1028.3572767020612;1033.0083120204538;5450.264214331094;5473.781048758005;5481.835544933659;5483.479069488392;5489.780408783684;5783.016887129198;5798.275215598051;5800.767969281266;5817.621854579439;5841.532677385884;5187.688738675692;5361.418381577444;5440.972795135999;5471.565153506652;5507.9449205507435;2716.1444145094233;2727.6361819090453;2734.3164208947765;2735.2478068531314;2737.8767955442054;5253.395859476209;5268.236105764643;5283.973580132151;5301;5316.441773613715;5217.6086793489885;5581.162825576196;5914.8817023659285;5942.792002279986;5946.375630558816;6035.2154219952;6036.348009454217;6037.1927053920135;6041.24484439445;6059.515238780887;5660.29246344207;5843.737031833492;5849.766009359578;5859.619124756877;5887.20522729426;5745.626534275259;5767.538596912321;5769.30768307789;5783.062674004255;5802.4197580241635;5959.880802384015;6020.30766461845;6032.245969253843;6039.1314125789895;6049.51603871698;4099.877003689894;4191.517975432865;4194.559571244975;4240.766757828349;4252.255855225261;5579.218909352613;5685.204071430086;5853.443922827325;5856.189029265891;5864.149698293809;5603.579731520071;5650.971745141413;5654.660720356792;5716.485516303553;5724.513416359656;5670.950874088307;5698.743556539883;5701.201831743476;5713.457221564111;5717.856428714191;5417.241586178017;5638.520725738358;5658.151277308437;5705.515031222227;5711.543076553949;5723.198752174616;5765.509931655856;5766.308043034863;5771.624844385269;5777.653340799566;5488.204210389551;5613.298337707786;5657.12314591239;5676.687782171855;5726.484616384545 04:22:29 INFO - PROCESS | 14704 | |5;yui.html;794.6066696984991;833.516560394971;840.9915900840994;885.9069797671249;935.5415846235343;16983.490495285132;17771.28914843406;17854.732179017326;18060.548486287815;18106.818931810678;14906.105633661988;14934.253287335618;14940.551783446486;14946.327415266313;14946.775798363033;859.3855393393707;893.9776505587347;897.1611543207122;897.4391005621486;898.1422741282059;884.8539990901494;885.026470882031;886.561152229644;887.4542156573725;887.8401887660218;924.3806649544788;932.5616960028775;933.5239028095652;935.0742764663003;935.3218916285673;930.8277968575835;934.1685899549407;935.1303287942211;935.9438433693966;936.9578368973413;685.25649670108;685.2975699907585;686.9141357330334;687.4500399680236;687.6355531568277;15078.095314281178;15081.321340539593;15093.849061509354;15096.24518774057;15116.168610726405;12801.359932003455;13377.799333010013;13385.129966552144;15740.527784166494;15994.520164395086;14404.711905761826;14548.927255363762;14740.557783266519;14864.182469964147;14882.404703811835;4709.552592503706;4799.256115302133;4865.854024379239;4899.28960300758;4908.410990681105;72893.27106728971;75226.24773752247;75463;80879;82474;11171.329720216812;11353.99738396286;11391.601293954673;11486.655400338002;11564.13269004828;21219.78780212178;21230.256941006992;21256.893715531634;21262.468438288855;21273;8985.775355616162;9267.119623635745;9376.95311523438;9387.859182112274;9392;4868.342773725572;5020.221865610836;5066.923996140061;5119.436861945183;5172.224166374998;4016.638502534786;4181.163767246563;4183.560726123713;4184.016756062323;4184.372344148342;693.3170826735661;703.8381172330393;714.3499415531841;716.659586696315;722.1118024829489;777.149021821105;812.2608426332009;831.7354384042729;848.6859861851154;867.9262262707614;10100.191984641211;10178.745531361776;10340.793184136275;10683.946580267204;10965.56137754481;698.4656737595743;722.884338505847;724.2431658916445;753.9700301886409;756.0511557994681;4794.2089555223;4949.183384741549;5033.5469807716745;5075.682135032876;5109.4379618241965;105521;108760;116470;117978;118393.408032961;86659.56670216734;95975.52012240034;96179;97061;99849.50075249723;56444.71777641168;73945.26054739383;83675;88489.55755221183;89103.55448222718;10205.642802501878;10246.436445995541;10314.845277320695;10657.786844263226;10697.090747286567;50243.24635130549;50475.49524504708;51415.74292128516;52214.73892630512;52319.738401307746;77565.70480170417;78598;79400;81051;82167.17832821596;12697.746045079231;12915.741685166056;13117.934410327887;13126.868731312565;13718.588442346745;14761.261936902896;14770.187639679922;15800.36798528046;16000.919995399949;16093.517194484184;12809.853733641188;14330.785038224643;14609.707805844037;14739.2630368479;15539.689206215586;13015.674608134872;13370.933145334211;13518.25649589282;13537.323133843072;14591.562253132422;14203.573892783234;14289.142651440947;14357.56927292183;14361.9281903594;15430.691386172439;10837.458127093456;11947.283163010246;12018;12146.210496317712;12147.878521214674;11271.605493807678;11387.373694446886;11785.646430607096;11791.823122652995;11894.387591940991 04:22:29 INFO - PROCESS | 14704 | __end_tp_report 04:22:29 INFO - PROCESS | 14704 | __start_cc_report 04:22:29 INFO - PROCESS | 14704 | _x_x_mozilla_cycle_collect,1073 04:22:29 INFO - PROCESS | 14704 | __end_cc_report 04:22:29 INFO - PROCESS | 14704 | __startTimestamp1474975349949__endTimestamp 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | ------- Summary: start ------- 04:22:29 INFO - PROCESS | 14704 | Number of tests: 6 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#0] dojo.html Cycles:165 Average:5668.21 Median:5877.47 stddev:659.20 (11.2%) stddev-sans-first:632.57 04:22:29 INFO - PROCESS | 14704 | Values: 3217.5 3219.6 3224.4 3249.0 3279.4 5644.2 5695.3 5705.4 5718.7 5718.8 5708.7 5766.4 5769.5 5786.1 5791.6 5065.6 5151.6 5227.3 5231.5 5258.8 5222.2 5277.4 5279.6 5281.1 5281.6 5187.6 5219.8 5244.1 5248.8 5261.6 5324.2 5328.2 5330.5 5332.1 5341.3 5016.7 5032.5 5061.2 5205.0 5212.4 5604.7 5640.7 5642.4 5660.5 5697.2 6161.1 6165.4 6168.4 6170.4 6179.9 5943.8 5944.8 6092.8 6201.9 6222.9 5791.8 5807.8 5810.3 5812.4 5814.9 6302.2 6305.6 6306.1 6311.4 6312.4 5799.4 5833.4 5877.5 5879.9 6050.8 6117.5 6117.8 6122.1 6123.1 6128.2 6077.8 6085.5 6088.5 6094.3 6109.0 5839.2 5879.2 5988.9 6018.0 6052.4 4638.3 4673.7 4677.5 4698.4 4703.7 4125.9 4126.3 4131.7 4142.9 4161.4 5379.6 5520.7 5573.7 5787.9 5794.5 6098.5 6102.0 6103.8 6127.0 6138.3 5695.5 5715.4 5715.9 5718.4 5722.0 5026.2 5039.2 5059.6 5062.2 5076.4 6299.3 6338.3 6339.4 6345.6 6347.3 6107.2 6107.9 6145.1 6168.3 6174.7 6121.5 6276.6 6311.1 6332.0 6338.7 6116.1 6116.7 6118.8 6120.8 6126.0 6230.1 6235.4 6235.8 6242.8 6254.4 5853.5 5864.9 5868.6 6079.7 6093.9 5921.9 5954.8 5965.9 5992.1 6009.5 5869.7 5903.4 5909.5 5921.6 5922.1 5953.6 5962.5 5963.9 5977.6 6026.3 5922.9 5932.1 5932.7 5934.1 5959.1 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#1] ext.html Cycles:180 Average:65713.37 Median:22440.86 stddev:131847.67 (587.5%) stddev-sans-first:132126.87 04:22:29 INFO - PROCESS | 14704 | Values: 591.0 634.9 665.8 670.4 679.6 31354.7 33421.3 33435.3 47195.8 48778.8 48785.0 49235.5 49271.0 49277.0 49360.0 9265.6 9403.5 9405.9 9411.7 9416.9 8541.0 10956.5 11258.2 11277.6 11648.5 11438.7 11506.5 11526.0 11531.7 11540.4 11098.1 11149.5 11154.2 11158.6 11164.4 11229.9 11285.3 11291.8 11304.6 11307.9 45471.0 45551.5 45773.0 45792.0 45805.3 3932.1 3934.9 4077.7 4340.0 4698.7 35692.6 35904.3 36185.8 36221.3 37689.8 4497.9 4592.8 4603.6 4612.6 4632.9 370138.0 370714.0 371854.0 372071.0 372400.0 65811.3 66129.0 66183.0 66215.7 66261.0 40804.0 41648.2 41761.8 43579.6 43583.0 18745.2 18806.2 18811.1 19002.0 19039.8 13670.2 15146.8 15302.6 15374.5 15385.0 20530.2 21118.8 21467.4 21491.8 21512.1 7747.9 7832.3 7918.2 7947.3 7969.6 1084.7 1113.5 1121.5 1142.1 1190.1 35764.3 36292.3 36317.3 36323.6 36399.5 1181.7 1248.9 1262.9 1303.4 1311.8 18844.7 18955.4 19008.5 19061.2 19065.6 633394.0 638356.0 641193.0 643076.0 643750.0 66220.0 66433.7 66448.0 66475.3 66597.3 1361.4 1431.4 1482.6 1499.9 1500.9 34338.7 36203.6 36671.6 36682.6 36792.8 55502.4 56475.7 56544.7 56605.0 56653.7 435700.0 437656.0 438263.0 438427.0 438871.0 28958.9 29748.4 30881.5 31034.8 31845.2 53400.7 53696.5 53737.7 54343.7 54353.2 44460.3 45652.3 46348.3 46451.3 46899.8 44495.8 44530.8 46164.3 46561.8 47853.3 45280.1 45619.5 45751.8 45901.8 46120.5 39540.2 41139.0 41212.1 41426.8 42150.0 30178.8 30369.7 30503.4 30544.4 31038.4 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#2] jquery.html Cycles:180 Average:31966.62 Median:15737.72 stddev:81460.66 (517.6%) stddev-sans-first:81654.75 04:22:29 INFO - PROCESS | 14704 | Values: 427.3 429.7 430.6 437.4 438.8 19508.3 20849.8 21389.0 21518.9 21787.5 19709.6 20735.7 20800.4 20892.9 20986.1 5879.6 6133.4 6182.0 6184.5 6191.2 5797.2 6017.0 6033.0 6034.8 6050.0 5850.8 6004.8 6016.8 6019.9 6026.3 6028.7 6061.0 6073.5 6090.5 6093.6 5487.2 5490.8 5497.7 7209.8 7592.3 26996.7 28657.4 28679.7 29006.7 29220.6 2618.3 2818.0 3029.2 3411.2 3611.7 16656.3 16744.0 16796.2 16812.0 16900.7 2203.4 2244.4 2268.9 2272.8 2290.4 97029.5 99889.0 102997.5 103247.5 103506.5 18850.6 18969.7 18980.4 19269.0 19497.9 35574.3 35613.0 35614.6 35701.0 35937.0 10774.1 10876.2 10995.4 11073.0 11352.8 9313.8 9318.3 9351.0 9415.8 9597.4 9418.3 9668.3 9680.2 9706.6 9881.3 3342.0 3377.5 3621.9 3668.2 3701.7 1002.6 1039.2 1085.1 1101.5 1142.5 20953.5 21785.6 21814.3 21852.7 22229.4 994.7 1021.0 1050.3 1061.8 1111.9 11761.3 12119.6 12152.8 12271.9 12334.8 431024.0 437502.0 440674.0 534878.0 589413.0 97474.5 100876.0 101912.0 105912.0 107785.0 1047.3 1068.3 1081.9 1098.7 1109.2 19178.0 19235.9 19527.4 19704.4 19772.4 46086.8 46182.5 46757.8 46879.5 47030.3 64633.7 64857.0 65245.7 66372.3 66378.7 8997.4 9047.0 9177.7 9301.0 9441.4 10204.5 13679.7 14135.3 14475.9 15077.9 12200.8 12403.0 12431.4 12542.1 12639.3 7073.0 8832.6 11160.8 12515.2 12976.2 12496.8 12518.7 12723.8 12892.0 12908.8 9222.0 9371.7 9382.4 9498.7 9621.1 9611.0 12438.8 12563.4 12641.7 12765.2 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#3] mootools.html Cycles:180 Average:6545.85 Median:4521.54 stddev:3755.53 (83.1%) stddev-sans-first:3746.65 04:22:29 INFO - PROCESS | 14704 | Values: 1464.2 1558.5 1579.0 1751.8 1860.2 9258.8 9777.4 9788.7 10016.4 10035.6 9874.5 9987.7 10006.3 10014.0 10021.4 2587.4 2591.8 2612.7 2615.1 2615.3 2524.7 2618.9 2620.9 2623.6 2624.9 2482.3 2503.1 2541.9 2549.4 2558.6 2487.6 2507.3 2508.6 2528.5 2543.3 2452.3 2467.1 2476.7 2477.4 2501.8 9698.1 9835.5 9902.0 9904.6 9919.6 5810.4 6047.7 6048.2 6057.3 6063.8 3609.2 3682.8 3735.5 3762.7 3859.1 1079.5 1097.0 1104.8 1119.4 1140.5 13799.2 14014.6 14025.6 14051.0 14086.9 5134.9 5238.3 5385.6 5439.8 5452.7 11785.4 11849.8 11854.9 11863.5 11867.3 5980.8 5996.9 6017.9 6021.6 6025.8 4777.2 4967.8 5451.6 5478.0 5520.1 4511.6 4531.4 4623.2 4654.4 4665.1 1430.9 1446.4 1461.5 1465.0 1467.5 1030.6 1083.8 1110.9 1118.2 1127.8 6470.5 6786.4 6875.2 6924.5 7036.4 1027.2 1057.8 1077.9 1080.8 1097.4 4548.6 4638.2 4669.0 4739.7 4783.4 12309.6 12581.4 12636.9 12671.2 12717.7 11506.5 12547.4 12618.6 12620.9 12649.6 8766.0 9129.3 9204.6 9206.0 9214.9 6601.1 6753.8 6767.8 6790.2 6840.3 10974.7 11335.7 11362.4 11531.4 11587.9 10406.3 11035.9 11206.2 11725.0 11747.4 8496.8 8705.1 8778.9 8807.5 8967.0 9695.0 9755.4 9817.8 9829.2 9829.5 8575.4 8709.6 8751.4 8782.2 8912.6 8445.2 8548.8 8635.6 8635.8 8900.1 7531.4 7658.5 7668.6 7671.5 7671.8 5663.9 5748.6 5896.6 5904.4 5973.7 5759.6 5849.2 5895.9 5986.2 6026.0 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#4] prototype.html Cycles:180 Average:4776.26 Median:5391.46 stddev:1397.61 (25.9%) stddev-sans-first:1361.52 04:22:29 INFO - PROCESS | 14704 | Values: 352.6 356.3 356.4 364.9 370.8 5244.8 5374.2 5450.2 5491.3 5557.3 5362.3 5380.5 5402.5 5404.7 5412.6 4242.7 4255.0 4260.9 4276.7 4278.3 4171.0 4263.6 4270.7 4278.4 4292.6 4237.9 4243.5 4243.6 4246.0 4251.5 4218.9 4233.6 4256.8 4262.9 4280.5 3616.4 3772.2 3785.2 3789.0 3820.4 5233.6 5280.9 5289.7 5306.4 5308.5 4869.6 4877.5 4878.0 4898.1 4910.4 5487.4 5661.3 5663.2 5674.6 5702.3 4127.4 4133.6 4146.6 4161.9 4172.1 5951.9 5990.4 5992.4 5992.6 6002.5 4089.3 4240.7 4240.9 4249.3 4264.7 4337.3 4337.6 4340.0 4348.2 4363.8 4914.9 4933.2 4938.5 4960.0 4965.0 5471.9 5679.8 5690.9 5699.6 5787.0 1384.3 1446.0 1463.6 1471.2 1472.0 993.7 1003.8 1022.4 1028.4 1033.0 5450.3 5473.8 5481.8 5483.5 5489.8 5783.0 5798.3 5800.8 5817.6 5841.5 5187.7 5361.4 5441.0 5471.6 5507.9 2716.1 2727.6 2734.3 2735.2 2737.9 5253.4 5268.2 5284.0 5301.0 5316.4 5217.6 5581.2 5914.9 5942.8 5946.4 6035.2 6036.3 6037.2 6041.2 6059.5 5660.3 5843.7 5849.8 5859.6 5887.2 5745.6 5767.5 5769.3 5783.1 5802.4 5959.9 6020.3 6032.2 6039.1 6049.5 4099.9 4191.5 4194.6 4240.8 4252.3 5579.2 5685.2 5853.4 5856.2 5864.1 5603.6 5651.0 5654.7 5716.5 5724.5 5671.0 5698.7 5701.2 5713.5 5717.9 5417.2 5638.5 5658.2 5705.5 5711.5 5723.2 5765.5 5766.3 5771.6 5777.7 5488.2 5613.3 5657.1 5676.7 5726.5 04:22:29 INFO - PROCESS | 14704 | 04:22:29 INFO - PROCESS | 14704 | [#5] yui.html Cycles:180 Average:20999.53 Median:4751.88 stddev:29350.28 (617.7%) stddev-sans-first:29393.41 04:22:29 INFO - PROCESS | 14704 | Values: 794.6 833.5 841.0 885.9 935.5 16983.5 17771.3 17854.7 18060.5 18106.8 14906.1 14934.3 14940.6 14946.3 14946.8 859.4 894.0 897.2 897.4 898.1 884.9 885.0 886.6 887.5 887.8 924.4 932.6 933.5 935.1 935.3 930.8 934.2 935.1 935.9 937.0 685.3 685.3 686.9 687.5 687.6 15078.1 15081.3 15093.8 15096.2 15116.2 12801.4 13377.8 13385.1 15740.5 15994.5 14404.7 14548.9 14740.6 14864.2 14882.4 4709.6 4799.3 4865.9 4899.3 4908.4 72893.3 75226.2 75463.0 80879.0 82474.0 11171.3 11354.0 11391.6 11486.7 11564.1 21219.8 21230.3 21256.9 21262.5 21273.0 8985.8 9267.1 9377.0 9387.9 9392.0 4868.3 5020.2 5066.9 5119.4 5172.2 4016.6 4181.2 4183.6 4184.0 4184.4 693.3 703.8 714.3 716.7 722.1 777.1 812.3 831.7 848.7 867.9 10100.2 10178.7 10340.8 10683.9 10965.6 698.5 722.9 724.2 754.0 756.1 4794.2 4949.2 5033.5 5075.7 5109.4 105521.0 108760.0 116470.0 117978.0 118393.4 86659.6 95975.5 96179.0 97061.0 99849.5 56444.7 73945.3 83675.0 88489.6 89103.6 10205.6 10246.4 10314.8 10657.8 10697.1 50243.2 50475.5 51415.7 52214.7 52319.7 77565.7 78598.0 79400.0 81051.0 82167.2 12697.7 12915.7 13117.9 13126.9 13718.6 14761.3 14770.2 15800.4 16000.9 16093.5 12809.9 14330.8 14609.7 14739.3 15539.7 13015.7 13370.9 13518.3 13537.3 14591.6 14203.6 14289.1 14357.6 14361.9 15430.7 10837.5 11947.3 12018.0 12146.2 12147.9 11271.6 11387.4 11785.6 11791.8 11894.4 04:22:29 INFO - PROCESS | 14704 | -------- Summary: end -------- 04:22:29 INFO - PROCESS | 14704 | 04:22:30 INFO - TEST-INFO | 14704: exit 0 04:22:30 INFO - TEST-OK | dromaeo_css | took 1114384ms 04:22:30 INFO - TEST-START | kraken 04:22:30 INFO - operating with platform_type : linux_ 04:22:30 INFO - Initialising browser for kraken test... 04:22:30 INFO - TEST-INFO | started process 14895 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdwXVbk/profile http://localhost:51084/getInfo.html) 04:22:35 INFO - PROCESS | 14895 | __metrics Screen width/height:1600/1200 04:22:35 INFO - PROCESS | 14895 | colorDepth:24 04:22:35 INFO - PROCESS | 14895 | Browser inner width/height: 1024/697 04:22:35 INFO - PROCESS | 14895 | __metrics 04:22:36 INFO - PROCESS | 14895 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:22:36 INFO - PROCESS | 14895 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:22:36 INFO - PROCESS | 14895 | console.error: PushService: 04:22:36 INFO - PROCESS | 14895 | stateChangeProcessEnqueue: Error transitioning state 04:22:36 INFO - PROCESS | 14895 | UnknownError 04:22:37 INFO - TEST-INFO | 14895: exit 0 04:22:37 INFO - Browser initialized. 04:22:37 INFO - Running cycle 1/1 for kraken test... 04:22:37 INFO - TEST-INFO | started process 14982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdwXVbk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:22:49 INFO - PROCESS | 14982 | RSS: Main: 143564800 04:22:49 INFO - PROCESS | 14982 | 04:22:51 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51084/tests/kraken/driver.html?testName=audio-beat-detection) 04:22:52 INFO - PROCESS | 14982 | RSS: Main: 198807552 04:22:52 INFO - PROCESS | 14982 | 04:22:54 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51084/tests/kraken/driver.html?testName=audio-dft) 04:22:55 INFO - PROCESS | 14982 | RSS: Main: 183029760 04:22:55 INFO - PROCESS | 14982 | 04:22:58 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51084/tests/kraken/driver.html?testName=audio-fft) 04:22:58 INFO - PROCESS | 14982 | RSS: Main: 274710528 04:22:58 INFO - PROCESS | 14982 | 04:23:01 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51084/tests/kraken/driver.html?testName=audio-oscillator) 04:23:01 INFO - PROCESS | 14982 | RSS: Main: 179535872 04:23:01 INFO - PROCESS | 14982 | 04:23:03 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51084/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:23:04 INFO - PROCESS | 14982 | RSS: Main: 178335744 04:23:04 INFO - PROCESS | 14982 | 04:23:07 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51084/tests/kraken/driver.html?testName=imaging-darkroom) 04:23:07 INFO - PROCESS | 14982 | RSS: Main: 214294528 04:23:07 INFO - PROCESS | 14982 | 04:23:11 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51084/tests/kraken/driver.html?testName=imaging-desaturate) 04:23:11 INFO - PROCESS | 14982 | RSS: Main: 213164032 04:23:11 INFO - PROCESS | 14982 | 04:23:14 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51084/tests/kraken/driver.html?testName=json-parse-financial) 04:23:15 INFO - PROCESS | 14982 | RSS: Main: 213315584 04:23:15 INFO - PROCESS | 14982 | 04:23:16 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51084/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:23:17 INFO - PROCESS | 14982 | RSS: Main: 175448064 04:23:17 INFO - PROCESS | 14982 | 04:23:19 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:23:19 INFO - PROCESS | 14982 | RSS: Main: 183685120 04:23:19 INFO - PROCESS | 14982 | 04:23:21 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:23:22 INFO - PROCESS | 14982 | RSS: Main: 184975360 04:23:22 INFO - PROCESS | 14982 | 04:23:24 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:23:25 INFO - PROCESS | 14982 | RSS: Main: 185069568 04:23:25 INFO - PROCESS | 14982 | 04:23:27 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:23:28 INFO - PROCESS | 14982 | RSS: Main: 182976512 04:23:28 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | Cycle 1(1): loaded http://localhost:51084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51084/tests/kraken/driver.html?testName=ai-astar) 04:23:30 INFO - PROCESS | 14982 | RSS: Main: 233189376 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | __start_tp_report 04:23:30 INFO - PROCESS | 14982 | _x_x_mozilla_page_load 04:23:30 INFO - PROCESS | 14982 | _x_x_mozilla_page_load_details 04:23:30 INFO - PROCESS | 14982 | |i|pagename|runs| 04:23:30 INFO - PROCESS | 14982 | |0;ai-astar;110;110;117;110;109;108;110;108;110;110 04:23:30 INFO - PROCESS | 14982 | |1;audio-beat-detection;153;116;149;181;123;153;140;116;169;119 04:23:30 INFO - PROCESS | 14982 | |2;audio-dft;160;161;160;160;161;160;161;163;160;159 04:23:30 INFO - PROCESS | 14982 | |3;audio-fft;75;75;75;191;76;74;74;75;169;75 04:23:30 INFO - PROCESS | 14982 | |4;audio-oscillator;82;83;160;83;84;84;132;83;84;144 04:23:30 INFO - PROCESS | 14982 | |5;imaging-gaussian-blur;116;117;117;117;117;117;116;117;116;117 04:23:30 INFO - PROCESS | 14982 | |6;imaging-darkroom;146;146;146;146;147;146;145;146;145;145 04:23:30 INFO - PROCESS | 14982 | |7;imaging-desaturate;94;95;94;95;94;95;94;95;95;95 04:23:30 INFO - PROCESS | 14982 | |8;json-parse-financial;50;49;49;51;50;49;49;49;50;50 04:23:30 INFO - PROCESS | 14982 | |9;json-stringify-tinderbox;60;60;61;61;61;59;60;59;60;59 04:23:30 INFO - PROCESS | 14982 | |10;stanford-crypto-aes;73;72;73;72;76;72;72;81;71;72 04:23:30 INFO - PROCESS | 14982 | |11;stanford-crypto-ccm;123;118;119;122;117;129;123;115;151;115 04:23:30 INFO - PROCESS | 14982 | |12;stanford-crypto-pbkdf2;149;152;151;150;152;150;153;151;150;148 04:23:30 INFO - PROCESS | 14982 | |13;stanford-crypto-sha256-iterative;67;61;61;61;60;61;65;61;58;59 04:23:30 INFO - PROCESS | 14982 | __end_tp_report 04:23:30 INFO - PROCESS | 14982 | __start_cc_report 04:23:30 INFO - PROCESS | 14982 | _x_x_mozilla_cycle_collect,1696 04:23:30 INFO - PROCESS | 14982 | __end_cc_report 04:23:30 INFO - PROCESS | 14982 | __startTimestamp1474975410422__endTimestamp 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | ------- Summary: start ------- 04:23:30 INFO - PROCESS | 14982 | Number of tests: 14 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.00 stddev:2.53 (2.3%) stddev-sans-first:2.68 04:23:30 INFO - PROCESS | 14982 | Values: 110.0 110.0 117.0 110.0 109.0 108.0 110.0 108.0 110.0 110.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:151.00 stddev:23.06 (15.3%) stddev-sans-first:24.11 04:23:30 INFO - PROCESS | 14982 | Values: 153.0 116.0 149.0 181.0 123.0 153.0 140.0 116.0 169.0 119.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.08 (0.7%) stddev-sans-first:1.13 04:23:30 INFO - PROCESS | 14982 | Values: 160.0 161.0 160.0 160.0 161.0 160.0 161.0 163.0 160.0 159.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#3] audio-fft Cycles:10 Average:95.90 Median:75.00 stddev:44.63 (59.5%) stddev-sans-first:46.69 04:23:30 INFO - PROCESS | 14982 | Values: 75.0 75.0 75.0 191.0 76.0 74.0 74.0 75.0 169.0 75.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:83.00 stddev:30.70 (37.0%) stddev-sans-first:31.71 04:23:30 INFO - PROCESS | 14982 | Values: 82.0 83.0 160.0 83.0 84.0 84.0 132.0 83.0 84.0 144.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 04:23:30 INFO - PROCESS | 14982 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 04:23:30 INFO - PROCESS | 14982 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 145.0 146.0 145.0 145.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 04:23:30 INFO - PROCESS | 14982 | Values: 94.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.70 (1.4%) stddev-sans-first:0.73 04:23:30 INFO - PROCESS | 14982 | Values: 50.0 49.0 49.0 51.0 50.0 49.0 49.0 49.0 50.0 50.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 04:23:30 INFO - PROCESS | 14982 | Values: 60.0 60.0 61.0 61.0 61.0 59.0 60.0 59.0 60.0 59.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:72.50 stddev:2.99 (4.1%) stddev-sans-first:3.17 04:23:30 INFO - PROCESS | 14982 | Values: 73.0 72.0 73.0 72.0 76.0 72.0 72.0 81.0 71.0 72.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#11] stanford-crypto-ccm Cycles:10 Average:123.20 Median:122.50 stddev:10.67 (8.7%) stddev-sans-first:11.32 04:23:30 INFO - PROCESS | 14982 | Values: 123.0 118.0 119.0 122.0 117.0 129.0 123.0 115.0 151.0 115.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.60 Median:151.00 stddev:1.51 (1.0%) stddev-sans-first:1.48 04:23:30 INFO - PROCESS | 14982 | Values: 149.0 152.0 151.0 150.0 152.0 150.0 153.0 151.0 150.0 148.0 04:23:30 INFO - PROCESS | 14982 | 04:23:30 INFO - PROCESS | 14982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.40 Median:61.00 stddev:2.67 (4.4%) stddev-sans-first:1.92 04:23:30 INFO - PROCESS | 14982 | Values: 67.0 61.0 61.0 61.0 60.0 61.0 65.0 61.0 58.0 59.0 04:23:30 INFO - PROCESS | 14982 | -------- Summary: end -------- 04:23:30 INFO - PROCESS | 14982 | 04:23:31 INFO - TEST-INFO | 14982: exit 0 04:23:31 INFO - TEST-OK | kraken | took 60634ms 04:23:31 INFO - SUITE-END | took 1175s 04:23:31 INFO - Completed test suite (00:19:35) 04:23:31 INFO - javascript benchmark 04:23:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.5495430639717, 3219.597550306212, 3224.3873664003836, 3249.0, 3279.360524697685, 5644.181593668921, 5695.259616249882, 5705.400932902048, 5718.685472299022, 5718.7998420055255, 5708.686022268774, 5766.365699773021, 5769.451902069297, 5786.132080187964, 5791.594588378815, 5065.569426598743, 5151.613628977823, 5227.32044834171, 5231.450697676746, 5258.789648414059, 5222.242774797652, 5277.366715994086, 5279.551238144743, 5281.075811732951, 5281.603879709018, 5187.559057480119, 5219.765110570014, 5244.082285600024, 5248.790048398059, 5261.579073674096, 5324.201369794523, 5328.227407025959, 5330.493603107699, 5332.066888294533, 5341.332333458317, 5016.749162541858, 5032.547070763649, 5061.240813877947, 5205.037068142406, 5212.374515058179, 5604.719764011783, 5640.746166422521, 5642.4357564243655, 5660.462256085665, 5697.202391665171, 6161.106639537287, 6165.414285642857, 6168.44483996438, 6170.382961703838, 6179.87640247197, 5943.791967281124, 5944.791932282349, 6092.766708022538, 6201.937980620181, 6222.875542489171, 5791.797287094973, 5807.796727114531, 5810.331811841608, 5812.418758124154, 5814.941850581525, 6302.212223472066, 6305.558610897193, 6306.085617585424, 6311.431971122621, 6312.400321969406, 5799.449052340021, 5833.416658334133, 5877.47102760745, 5879.911801322984, 6050.757969681164, 6117.480014198843, 6117.816465506042, 6122.051082082285, 6123.142760013601, 6128.23662163111, 6077.787277445268, 6085.5435842312, 6088.4824789893355, 6094.268687757498, 6108.969455152695, 5839.153322768175, 5879.206307148565, 5988.880222395528, 6017.969910150508, 6052.394760523913, 4638.304254361873, 4673.672842901033, 4677.508861569554, 4698.389209402824, 4703.717776933395, 4125.876223713293, 4126.298529250034, 4131.669466442738, 4142.896427589274, 4161.438205842233, 5379.6234263601955, 5520.696361700145, 5573.665580065128, 5787.884242315215, 5794.536437085023, 6098.451139397475, 6101.993171126803, 6103.816885493442, 6127.0, 6138.324784273726, 5695.487406133385, 5715.371309155989, 5715.8856822864145, 5718.428157184331, 5722.027255366597, 5026.220935754964, 5039.21892106724, 5059.620528460307, 5062.164742817467, 5076.36545431821, 6299.338569450233, 6338.271098823694, 6339.429451349401, 6345.587536810092, 6347.333529979378, 6107.175531303395, 6107.9083813741945, 6145.10895920098, 6168.290646575701, 6174.660393678211, 6121.544430001993, 6276.592021518587, 6311.084892690626, 6331.96834015827, 6338.651374174465, 6116.092308610985, 6116.731440059295, 6118.840189881278, 6120.751898419022, 6125.968084364015, 6230.127782110418, 6235.407636274728, 6235.781747638811, 6242.843928901632, 6254.437100660963, 5853.531717462678, 5864.882702346014, 5868.647881127146, 6079.726412311545, 6093.9390606093375, 5921.851953701192, 5954.821355359346, 5965.8806823862415, 5992.13114098462, 6009.519238461, 5869.73586188614, 5903.350631430453, 5909.527237820878, 5921.644701317935, 5922.141289512911, 5953.553483488669, 5962.52299816005, 5963.850903727268, 5977.581569290108, 6026.337102918587, 5922.881542369215, 5932.13983972313, 5932.673702946379, 5934.109883517507, 5959.0763431668165], "value": 5622.3207729153855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.9556783241258, 634.9428551430371, 665.8069159943614, 670.3665036540472, 679.5718697220751, 31354.68645313549, 33421.331573368545, 33435.33129337412, 47195.76402117993, 48778.75610621942, 48785.0, 49235.50764492354, 49271.0, 49277.0, 49360.0, 9265.629374825, 9403.529823508832, 9405.90594094057, 9411.717648470556, 9416.858747118798, 8541.0, 10956.506957186906, 11258.155638327158, 11277.605283815068, 11648.543932008499, 11438.714032149179, 11506.539738410453, 11526.0, 11531.654050378502, 11540.422978851066, 11098.112151027939, 11149.498272577672, 11154.163437742121, 11158.553657853676, 11164.385958772264, 11229.943850280779, 11285.322880627107, 11291.774164516664, 11304.604338848101, 11307.943460282646, 45471.0, 45551.544484555394, 45773.0, 45792.0, 45805.31292030622, 3932.134930315326, 3934.9016274593077, 4077.7145599808027, 4340.001799586082, 4698.694584851974, 35692.64307356919, 35904.281914361825, 36185.81907090448, 36221.27557448836, 37689.811550942344, 4497.887552811173, 4592.770361481913, 4603.608693261077, 4612.630989520831, 4632.930506042412, 370138.0, 370714.0, 371854.0, 372070.99999999726, 372400.0, 65811.34188658051, 66129.0, 66183.0, 66215.66892165605, 66261.0, 40804.0, 41648.1670366597, 41761.79119104446, 43579.56420435818, 43583.0, 18745.156467958976, 18806.247750090115, 18811.059447027594, 19002.0, 19039.809601904082, 13670.17978921268, 15146.848531514765, 15302.617434564225, 15374.461893833663, 15385.0, 20530.178792848117, 21118.78881211199, 21467.355979320644, 21491.785082148977, 21512.13951441956, 7747.922520774833, 7832.334251588679, 7918.168592297841, 7947.284744373034, 7969.561674107864, 1084.6637542361893, 1113.5267511307825, 1121.4785124916953, 1142.0920368307216, 1190.0955273991829, 35764.28471430557, 36292.27415451676, 36317.27365452676, 36323.63676363255, 36399.454008189896, 1181.7400171962154, 1248.9063320251018, 1262.8610852806185, 1303.4460354349364, 1311.8360204974379, 18844.717329240073, 18955.431337059912, 19008.524786880163, 19061.237550497826, 19065.61868762617, 633394.0, 638356.0, 641193.0, 643076.0, 643750.0, 66220.0, 66433.66783166054, 66448.0, 66475.33524664788, 66597.33402665911, 1361.4213959067356, 1431.434583339575, 1482.6219314074842, 1499.8575135362123, 1500.8874334424743, 34338.656613433544, 36203.63796362003, 36671.63328366682, 36682.61721171207, 36792.81603591965, 55502.444975549726, 56475.71762141163, 56544.717276415, 56605.0, 56653.716731416076, 435700.0, 437656.0, 438263.0, 438427.0, 438871.0, 28958.855205724678, 29748.405031899674, 30881.536776948815, 31034.844825775726, 31845.20386990344, 53400.73299633477, 53696.46303537071, 53737.731311343196, 54343.72828135834, 54353.184702228704, 44460.333095002956, 45652.315215272465, 46348.30477542907, 46451.30323045225, 46899.765501172275, 44495.777521112184, 44530.77734611436, 46164.307535386324, 46561.767191163824, 47853.28220076771, 45280.09439811251, 45619.54380456153, 45751.77124114491, 45901.770491147334, 46120.538794611624, 39540.2091958165, 41139.0, 41212.09093180115, 41426.792866035474, 42150.0, 30178.826395614746, 30369.69630303757, 30503.389932201677, 30544.389112217188, 31038.37923241568], "value": 22225.018343180865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.2928303657448, 429.6820352938826, 430.55222568528734, 437.42478640587626, 438.8025388575141, 19508.31720889769, 20849.791502084976, 21389.0, 21518.89240553795, 21787.455313617167, 19709.605807883832, 20735.688964665544, 20800.375988720323, 20892.8955355223, 20986.05562749676, 5879.647221166743, 6133.386661333876, 6181.969090154543, 6184.474319682832, 6191.195144631191, 5797.188393624896, 6017.0, 6033.034714445689, 6034.788782392618, 6049.9697501512655, 5850.824475265727, 6004.849878753044, 6016.75932962681, 6019.939800602004, 6026.306974697922, 6028.728707208164, 6060.969695151518, 6073.51411887048, 6090.48230900374, 6093.634381937098, 5487.2317875497465, 5490.752916118744, 5497.725113744297, 7209.783706488814, 7592.278733520307, 26996.730032699816, 28657.426851463064, 28679.71320286791, 29006.694698738607, 29220.561691574643, 2618.2930608735533, 2818.0136952066614, 3029.2275469755073, 3411.232472693624, 3611.7291203159866, 16656.333746650118, 16744.0, 16796.16019199035, 16812.0, 16900.746488802677, 2203.372038968886, 2244.416451722564, 2268.88655567221, 2272.8181745460324, 2290.408166324405, 97029.51485242529, 99888.99999999927, 102997.48501257446, 103247.48376258145, 103506.48246758794, 18850.622987540173, 18969.715454268196, 18980.43058708241, 19269.0, 19497.902510487358, 35574.28851423009, 35613.0, 35614.643853561654, 35701.0, 35937.0, 10774.138068954464, 10876.238663293494, 10995.448248565304, 11073.0, 11352.829707554394, 9313.813723725487, 9318.30112741547, 9350.95324523373, 9415.811683766286, 9597.424154550748, 9418.293627977791, 9668.323217374857, 9680.177184939219, 9706.563204655808, 9881.25890558211, 3341.966580334214, 3377.4933759935716, 3621.8732344367822, 3668.192997540537, 3701.7038636908983, 1002.5937749401495, 1039.2361614213546, 1085.1210519479089, 1101.4712937789907, 1142.5372724046774, 20953.476163096046, 21785.564288714137, 21814.345569632937, 21852.672209916866, 22229.444263893532, 994.6618149828948, 1020.9693709188737, 1050.3225419604464, 1061.7557961668858, 1111.8665760108838, 11761.294322340687, 12119.575814846614, 12152.817707734392, 12271.938640306742, 12334.81497777534, 431024.0, 437502.0, 440674.0, 534878.0, 589413.0, 97474.51262743783, 100876.0, 101912.0, 105912.0, 107785.0, 1047.3297089862494, 1068.2949253492657, 1081.9242653014371, 1098.7253186703324, 1109.1847492093304, 19178.0, 19235.90382048081, 19527.414177574694, 19704.40886773399, 19772.40682779519, 46086.769566151954, 46182.538174617825, 46757.766211168724, 46879.53120468752, 47030.294545582525, 64633.67683161554, 64857.0, 65245.67377163084, 66372.33627663662, 66378.66810665916, 8997.415168014059, 9047.0, 9177.678781242625, 9300.953495232481, 9441.386309889835, 10204.489775511343, 13679.658008549468, 14135.293235337986, 14475.855241447873, 15077.924610377317, 12200.75598488043, 12403.0, 12431.440585173508, 12542.12205145631, 12639.304838233986, 7072.9646351767915, 8832.646694132163, 11160.776784464102, 12515.249085054926, 12976.15654982423, 12496.812547811609, 12518.749625007267, 12723.80914286268, 12892.0, 12908.806367904677, 9221.953890230507, 9371.718848434557, 9382.390144640576, 9498.667546635834, 9621.08599683029, 9610.990845961074, 12438.751224975631, 12563.371831408576, 12641.747165056464, 12765.170263933187], "value": 10976.415255315696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.1507925403266, 1558.4623304959784, 1578.992105039475, 1751.8335758102976, 1860.1722233606051, 9258.814823703531, 9777.413355198683, 9788.706338809838, 10016.449095299773, 10035.59857605695, 9874.506274686273, 9987.650432234874, 10006.299559030871, 10014.0, 10021.398716077023, 2587.4178309880267, 2591.818572699912, 2612.725663805302, 2615.1370047884247, 2615.3330900620317, 2524.67179266696, 2618.8559629220385, 2620.9475810483777, 2623.645807815949, 2624.868756562174, 2482.317362725256, 2503.0613519930025, 2541.923742287725, 2549.375403026261, 2558.5906254999204, 2487.6268559716004, 2507.2979565721625, 2508.611165269386, 2528.5195812795696, 2543.262453888389, 2452.31335226137, 2467.087177744246, 2476.72755996842, 2477.4054226985395, 2501.774840264367, 9698.078682525149, 9835.45904975226, 9901.950490247502, 9904.55429505674, 9919.553620087041, 5810.360860305363, 6047.697615119227, 6048.213732214828, 6057.3336932937345, 6063.848403789896, 3609.205974685565, 3682.760620559655, 3735.514383130203, 3762.717796165271, 3859.0545316397383, 1079.4602698650674, 1096.961606343782, 1104.7735214281054, 1119.4011204005817, 1140.5380820767602, 13799.172049677052, 14014.649633759136, 14025.64935876601, 14051.016428849985, 14086.859131408759, 5134.871628209325, 5238.266642670029, 5385.5960802939935, 5439.83680489578, 5452.672839629635, 11785.351805650771, 11849.763004739856, 11854.88145118555, 11863.46614402354, 11867.287962722092, 5980.790672326448, 5996.910046349309, 6017.909731354033, 6021.608595441282, 6025.8192254232445, 4777.235642297216, 4967.751612419364, 5451.64564303319, 5478.0, 5520.1443776214755, 4511.639068874548, 4531.43357080365, 4623.237165867594, 4654.371659825948, 4665.113628410591, 1430.8783753381078, 1446.3708286895235, 1461.5323096608984, 1465.0184376467791, 1467.5377256164275, 1030.5517100061497, 1083.7940791249637, 1110.8833572474934, 1118.245184500459, 1127.7969965406141, 6470.54706170573, 6786.355296246848, 6875.209350924608, 6924.480663950224, 7036.437085033185, 1027.2039169643585, 1057.8095942730383, 1077.8814330423647, 1080.8162612355918, 1097.3744965369663, 4548.56788605082, 4638.2115040443205, 4668.976655116703, 4739.6558472766355, 4783.425988881287, 12309.630711078682, 12581.37093145339, 12636.936815316049, 12671.176373535875, 12717.682057948441, 11506.539738410538, 12547.435365408579, 12618.62144135659, 12620.936895315648, 12649.620511384674, 8766.0, 9129.315301352291, 9204.585793639302, 9206.0, 9214.90785092154, 6601.10885030534, 6753.763618273337, 6767.830804229934, 6790.151231096113, 6840.31596840312, 10974.725631858948, 11335.659930201777, 11362.431878406212, 11531.423428828693, 11587.942060289644, 10406.323588966692, 11035.889641103486, 11206.159538034517, 11725.0, 11747.412629368668, 8496.787580310294, 8705.129487051245, 8778.868316975379, 8807.471551706918, 8967.0, 9694.951525242328, 9755.365901216399, 9817.803643927224, 9829.213662907094, 9829.459379734184, 8575.356848236532, 8709.608067636846, 8751.431156974777, 8782.209601135928, 8912.55437228149, 8445.1977062179, 8548.786280343042, 8635.568221589021, 8635.827283454422, 8900.109989001048, 7531.3598344141365, 7658.502197357154, 7668.616569171408, 7671.53970761756, 7671.769846904602, 5663.943360566342, 5748.626339287933, 5896.64620122794, 5904.409559044061, 5973.731182096903, 5759.596828221985, 5849.151872978309, 5895.882082358243, 5986.161937328865, 6025.969870150621], "value": 5201.598516193138, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.6191712950014, 356.2607589252301, 356.4314917706258, 364.872294696856, 370.8127395665187, 5244.842654720364, 5374.247605335257, 5450.209719590662, 5491.341039075307, 5557.305336832896, 5362.27609272748, 5380.461953804617, 5402.459754024596, 5404.675719456825, 5412.648177868446, 4242.745435273878, 4255.000074982377, 4260.893477663052, 4276.743395396271, 4278.336857787048, 4171.0, 4263.5523270056665, 4270.67969902257, 4278.443802305712, 4292.635126014293, 4237.87286381409, 4243.511996120455, 4243.618074373306, 4245.978770106145, 4251.532331443539, 4218.894527636803, 4233.640140588053, 4256.808443620013, 4262.850800221977, 4280.4863416390235, 3616.4213725803747, 3772.2455508898056, 3785.2051069275485, 3788.9621103788886, 3820.3887378019385, 5233.581313494911, 5280.947190528084, 5289.735513224362, 5306.3632364116165, 5308.495692909168, 4869.610431165534, 4877.512248775094, 4877.975610121926, 4898.142825005645, 4910.386201724785, 5487.42382049887, 5661.320641523043, 5663.178839068353, 5674.574406919456, 5702.3157221133315, 4127.422160897477, 4133.586641335873, 4146.564610715862, 4161.854335098287, 4172.144710334404, 5951.940480595182, 5990.371011043865, 5992.370801065869, 5992.580519363602, 6002.499375156211, 4089.345704687236, 4240.681948853849, 4240.851570195028, 4249.2563801334645, 4264.658827293809, 4337.349397590324, 4337.609615134653, 4339.956600434019, 4348.239058164809, 4363.818090954523, 4914.852554423373, 4933.235348520985, 4938.506149385033, 4959.975200123977, 4964.975175124101, 5471.945280547223, 5679.801206957736, 5690.9146362804595, 5699.601027928005, 5786.987277226553, 1384.3424373422545, 1445.980583688497, 1463.6414078550822, 1471.220253265771, 1471.9705605887823, 993.7465946183677, 1003.7590978165188, 1022.3763504262394, 1028.3572767020612, 1033.0083120204538, 5450.264214331094, 5473.781048758005, 5481.835544933659, 5483.479069488392, 5489.780408783684, 5783.016887129198, 5798.275215598051, 5800.767969281266, 5817.621854579439, 5841.532677385884, 5187.688738675692, 5361.418381577444, 5440.972795135999, 5471.565153506652, 5507.9449205507435, 2716.1444145094233, 2727.6361819090453, 2734.3164208947765, 2735.2478068531314, 2737.8767955442054, 5253.395859476209, 5268.236105764643, 5283.973580132151, 5301.0, 5316.441773613715, 5217.6086793489885, 5581.162825576196, 5914.8817023659285, 5942.792002279986, 5946.375630558816, 6035.2154219952, 6036.348009454217, 6037.1927053920135, 6041.24484439445, 6059.515238780887, 5660.29246344207, 5843.737031833492, 5849.766009359578, 5859.619124756877, 5887.20522729426, 5745.626534275259, 5767.538596912321, 5769.30768307789, 5783.062674004255, 5802.4197580241635, 5959.880802384015, 6020.30766461845, 6032.245969253843, 6039.1314125789895, 6049.51603871698, 4099.877003689894, 4191.517975432865, 4194.559571244975, 4240.766757828349, 4252.255855225261, 5579.218909352613, 5685.204071430086, 5853.443922827325, 5856.189029265891, 5864.149698293809, 5603.579731520071, 5650.971745141413, 5654.660720356792, 5716.485516303553, 5724.513416359656, 5670.950874088307, 5698.743556539883, 5701.201831743476, 5713.457221564111, 5717.856428714191, 5417.241586178017, 5638.520725738358, 5658.151277308437, 5705.515031222227, 5711.543076553949, 5723.198752174616, 5765.509931655856, 5766.308043034863, 5771.624844385269, 5777.653340799566, 5488.204210389551, 5613.298337707786, 5657.12314591239, 5676.687782171855, 5726.484616384545], "value": 4337.975010409448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.6066696984991, 833.516560394971, 840.9915900840994, 885.9069797671249, 935.5415846235343, 16983.490495285132, 17771.28914843406, 17854.732179017326, 18060.548486287815, 18106.818931810678, 14906.105633661988, 14934.253287335618, 14940.551783446486, 14946.327415266313, 14946.775798363033, 859.3855393393707, 893.9776505587347, 897.1611543207122, 897.4391005621486, 898.1422741282059, 884.8539990901494, 885.026470882031, 886.561152229644, 887.4542156573725, 887.8401887660218, 924.3806649544788, 932.5616960028775, 933.5239028095652, 935.0742764663003, 935.3218916285673, 930.8277968575835, 934.1685899549407, 935.1303287942211, 935.9438433693966, 936.9578368973413, 685.25649670108, 685.2975699907585, 686.9141357330334, 687.4500399680236, 687.6355531568277, 15078.095314281178, 15081.321340539593, 15093.849061509354, 15096.24518774057, 15116.168610726405, 12801.359932003455, 13377.799333010013, 13385.129966552144, 15740.527784166494, 15994.520164395086, 14404.711905761826, 14548.927255363762, 14740.557783266519, 14864.182469964147, 14882.404703811835, 4709.552592503706, 4799.256115302133, 4865.854024379239, 4899.28960300758, 4908.410990681105, 72893.27106728971, 75226.24773752247, 75463.0, 80879.0, 82474.0, 11171.329720216812, 11353.99738396286, 11391.601293954673, 11486.655400338002, 11564.13269004828, 21219.78780212178, 21230.256941006992, 21256.893715531634, 21262.468438288855, 21273.0, 8985.775355616162, 9267.119623635745, 9376.95311523438, 9387.859182112274, 9392.0, 4868.342773725572, 5020.221865610836, 5066.923996140061, 5119.436861945183, 5172.224166374998, 4016.638502534786, 4181.163767246563, 4183.560726123713, 4184.016756062323, 4184.372344148342, 693.3170826735661, 703.8381172330393, 714.3499415531841, 716.659586696315, 722.1118024829489, 777.149021821105, 812.2608426332009, 831.7354384042729, 848.6859861851154, 867.9262262707614, 10100.191984641211, 10178.745531361776, 10340.793184136275, 10683.946580267204, 10965.56137754481, 698.4656737595743, 722.884338505847, 724.2431658916445, 753.9700301886409, 756.0511557994681, 4794.2089555223, 4949.183384741549, 5033.5469807716745, 5075.682135032876, 5109.4379618241965, 105521.0, 108760.0, 116470.0, 117978.0, 118393.408032961, 86659.56670216734, 95975.52012240034, 96179.0, 97061.0, 99849.50075249723, 56444.71777641168, 73945.26054739383, 83675.0, 88489.55755221183, 89103.55448222718, 10205.642802501878, 10246.436445995541, 10314.845277320695, 10657.786844263226, 10697.090747286567, 50243.24635130549, 50475.49524504708, 51415.74292128516, 52214.73892630512, 52319.738401307746, 77565.70480170417, 78598.0, 79400.0, 81051.0, 82167.17832821596, 12697.746045079231, 12915.741685166056, 13117.934410327887, 13126.868731312565, 13718.588442346745, 14761.261936902896, 14770.187639679922, 15800.36798528046, 16000.919995399949, 16093.517194484184, 12809.853733641188, 14330.785038224643, 14609.707805844037, 14739.2630368479, 15539.689206215586, 13015.674608134872, 13370.933145334211, 13518.25649589282, 13537.323133843072, 14591.562253132422, 14203.573892783234, 14289.142651440947, 14357.56927292183, 14361.9281903594, 15430.691386172439, 10837.458127093456, 11947.283163010246, 12018.0, 12146.210496317712, 12147.878521214674, 11271.605493807678, 11387.373694446886, 11785.646430607096, 11791.823122652995, 11894.387591940991], "value": 8039.567993175553, "unit": "score"}], "name": "dromaeo_css", "value": 7930.855643532037, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 117.0, 110.0, 109.0, 108.0, 110.0, 108.0, 110.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 149.0, 181.0, 123.0, 153.0, 140.0, 116.0, 169.0, 119.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 163.0, 160.0, 159.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 191.0, 76.0, 74.0, 74.0, 75.0, 169.0, 75.0], "value": 95.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 160.0, 83.0, 84.0, 84.0, 132.0, 83.0, 84.0, 144.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 51.0, 50.0, 49.0, 49.0, 49.0, 50.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 61.0, 61.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 73.0, 72.0, 76.0, 72.0, 72.0, 81.0, 71.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 118.0, 119.0, 122.0, 117.0, 129.0, 123.0, 115.0, 151.0, 115.0], "value": 123.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 151.0, 150.0, 152.0, 150.0, 153.0, 151.0, 150.0, 148.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 61.0, 61.0, 61.0, 60.0, 61.0, 65.0, 61.0, 58.0, 59.0], "value": 61.4, "unit": "score"}], "name": "kraken", "value": 1485.7000000000003, "alertThreshold": 2.0}]} 04:23:31 INFO - Return code: 0 04:23:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:23:31 INFO - # TBPL SUCCESS # 04:23:31 INFO - Running post-action listener: _resource_record_post_action 04:23:31 INFO - [mozharness: 2016-09-27 11:23:31.731725Z] Finished run-tests step (success) 04:23:31 INFO - Running post-run listener: _resource_record_post_run 04:23:31 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288915456; Read time: 12; Write time: 231892 04:23:31 INFO - TinderboxPrint: CPU usage
12.9% 04:23:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:23:31 INFO - TinderboxPrint: I/O write bytes / time
288,915,456 / 231,892 04:23:31 INFO - TinderboxPrint: CPU idle
8,273.9 (87.1%) 04:23:31 INFO - TinderboxPrint: CPU user
1,179.2 (12.4%) 04:23:31 INFO - TinderboxPrint: Swap in / out
0 / 0 04:23:31 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70217728; Read time: 0; Write time: 70484 04:23:31 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 218697728; Read time: 0; Write time: 161408 04:23:32 INFO - Running post-run listener: _upload_blobber_files 04:23:32 INFO - Blob upload gear active. 04:23:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:23:32 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 04:23:32 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:23:32 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:23:32 INFO - (blobuploader) - INFO - Open directory for files ... 04:23:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:23:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:23:36 INFO - (blobuploader) - INFO - Done attempting. 04:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:23:37 INFO - (blobuploader) - INFO - Done attempting. 04:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:23:37 INFO - (blobuploader) - INFO - Done attempting. 04:23:37 INFO - (blobuploader) - INFO - Iteration through files over. 04:23:37 INFO - Return code: 0 04:23:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:23:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:23:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbfeb9c24b7f0a41b1f09c695b8d01d7eb2242e9d51ea578a5631313c308e8966610d36718b1f543d60a0da0e08b47fc061073cffb2a41275e992daca1c2fa0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e92fc0b7224491f6d4bae51ec5e16cf24f6342fbefdc084437177a0afe761b773c718e6f6b79793c66710482dbdca3a0bcda3e94fce0f85ea590cf5b787d6ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db77591dc8b5e6ff0dcb4b549a18c0ed0715eace887a638c2e78c2e1ddb02de4eafb8ba13a4a18383dfcf42ae4181f31366ab31ae6ebea3e90c9a6b8495f4391"} 04:23:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:23:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:23:37 INFO - Contents: 04:23:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbfeb9c24b7f0a41b1f09c695b8d01d7eb2242e9d51ea578a5631313c308e8966610d36718b1f543d60a0da0e08b47fc061073cffb2a41275e992daca1c2fa0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e92fc0b7224491f6d4bae51ec5e16cf24f6342fbefdc084437177a0afe761b773c718e6f6b79793c66710482dbdca3a0bcda3e94fce0f85ea590cf5b787d6ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db77591dc8b5e6ff0dcb4b549a18c0ed0715eace887a638c2e78c2e1ddb02de4eafb8ba13a4a18383dfcf42ae4181f31366ab31ae6ebea3e90c9a6b8495f4391"} 04:23:37 INFO - Running post-run listener: copy_logs_to_upload_dir 04:23:37 INFO - Copying logs to upload dir... 04:23:37 INFO - mkdir: /builds/slave/test/build/upload/logs 04:23:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.427381 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-09-27 04:23:37.322641) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 04:23:37.327475) ========= 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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbfeb9c24b7f0a41b1f09c695b8d01d7eb2242e9d51ea578a5631313c308e8966610d36718b1f543d60a0da0e08b47fc061073cffb2a41275e992daca1c2fa0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e92fc0b7224491f6d4bae51ec5e16cf24f6342fbefdc084437177a0afe761b773c718e6f6b79793c66710482dbdca3a0bcda3e94fce0f85ea590cf5b787d6ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db77591dc8b5e6ff0dcb4b549a18c0ed0715eace887a638c2e78c2e1ddb02de4eafb8ba13a4a18383dfcf42ae4181f31366ab31ae6ebea3e90c9a6b8495f4391"} build_url:https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/aJKlL4sIRCiVy3EvdthOFQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbfeb9c24b7f0a41b1f09c695b8d01d7eb2242e9d51ea578a5631313c308e8966610d36718b1f543d60a0da0e08b47fc061073cffb2a41275e992daca1c2fa0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e92fc0b7224491f6d4bae51ec5e16cf24f6342fbefdc084437177a0afe761b773c718e6f6b79793c66710482dbdca3a0bcda3e94fce0f85ea590cf5b787d6ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/db77591dc8b5e6ff0dcb4b549a18c0ed0715eace887a638c2e78c2e1ddb02de4eafb8ba13a4a18383dfcf42ae4181f31366ab31ae6ebea3e90c9a6b8495f4391"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 04:23:37.355858) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 04:23:37.356219) ========= 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=df17445c3590ff7897617a0e00000225-1474973738.816174-648701907 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004433 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 04:23:37.408904) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 04:23:37.409667) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 04:23:37.410351) ========= ========= Total master_lag: 0.17 =========