builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1474573579.75 results: success (0) buildid: 20160922094859 builduid: a7e0d25f8cf9436283dd84e57c31c350 revision: 65b9f5cea747a0815157758fb7c4a837910dac8f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.755109) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.755583) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.755888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.770371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.770636) ========= 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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-22 12:46:20-- 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% 15.6M=0.001s 2016-09-22 12:46:20 (15.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095381 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.880378) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.880734) ========= 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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016287 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.930862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:19.931240) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 65b9f5cea747a0815157758fb7c4a837910dac8f --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 65b9f5cea747a0815157758fb7c4a837910dac8f --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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-22 12:46:20,667 truncating revision to first 12 chars 2016-09-22 12:46:20,667 Setting DEBUG logging. 2016-09-22 12:46:20,667 attempt 1/10 2016-09-22 12:46:20,667 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/65b9f5cea747?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 12:46:20,968 unpacking tar archive at: mozilla-beta-65b9f5cea747/testing/mozharness/ program finished with exit code 0 elapsedTime=0.637682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:20.582458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:20.582801) ========= script_repo_revision: 65b9f5cea747a0815157758fb7c4a837910dac8f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:20.583217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:20.583492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-22 12:46:20.592900) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-22 12:46:20.593190) ========= /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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:46:21 INFO - MultiFileLogger online at 20160922 12:46:21 in /builds/slave/test 12:46:21 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 12:46:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:46:21 INFO - {'append_to_log': False, 12:46:21 INFO - 'base_work_dir': '/builds/slave/test', 12:46:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:46:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:46:21 INFO - 'branch': 'Mozilla-Beta', 12:46:21 INFO - 'buildbot_json_path': 'buildprops.json', 12:46:21 INFO - 'config_files': ('talos/linux_config.py',), 12:46:21 INFO - 'default_actions': ('clobber', 12:46:21 INFO - 'read-buildbot-config', 12:46:21 INFO - 'download-and-extract', 12:46:21 INFO - 'populate-webroot', 12:46:21 INFO - 'create-virtualenv', 12:46:21 INFO - 'install', 12:46:21 INFO - 'run-tests'), 12:46:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:46:21 INFO - 'download_minidump_stackwalk': True, 12:46:21 INFO - 'download_symbols': 'ondemand', 12:46:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:46:21 INFO - 'tooltool.py': '/tools/tooltool.py', 12:46:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:46:21 INFO - '/tools/misc-python/virtualenv.py')}, 12:46:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:46:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:46:21 INFO - 'installer_path': 'installer.exe', 12:46:21 INFO - 'log_level': 'info', 12:46:21 INFO - 'log_name': 'talos', 12:46:21 INFO - 'log_to_console': True, 12:46:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:46:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:46:21 INFO - 'opt_config_files': (), 12:46:21 INFO - 'pip_index': False, 12:46:21 INFO - 'sps_profile': False, 12:46:21 INFO - 'sps_profile_interval': 0, 12:46:21 INFO - 'suite': 'dromaeojs', 12:46:21 INFO - 'system_bits': '32', 12:46:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:46:21 INFO - 'title': 'talos-linux64-ix-027', 12:46:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:46:21 INFO - 'use_talos_json': True, 12:46:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:46:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:46:21 INFO - 'work_dir': 'build'} 12:46:21 INFO - [mozharness: 2016-09-22 19:46:21.422881Z] Running clobber step. 12:46:21 INFO - Running pre-action listener: _resource_record_pre_action 12:46:21 INFO - Running main action method: clobber 12:46:21 INFO - rmtree: /builds/slave/test/build 12:46:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:46:22 INFO - Running post-action listener: _resource_record_post_action 12:46:22 INFO - [mozharness: 2016-09-22 19:46:22.403900Z] Finished clobber step (success) 12:46:22 INFO - [mozharness: 2016-09-22 19:46:22.403999Z] Running read-buildbot-config step. 12:46:22 INFO - Running pre-action listener: _resource_record_pre_action 12:46:22 INFO - Running main action method: read_buildbot_config 12:46:22 INFO - Using buildbot properties: 12:46:22 INFO - { 12:46:22 INFO - "project": "", 12:46:22 INFO - "product": "firefox", 12:46:22 INFO - "script_repo_revision": "production", 12:46:22 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 12:46:22 INFO - "repository": "", 12:46:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 12:46:22 INFO - "buildid": "20160922094859", 12:46:22 INFO - "pgo_build": "True", 12:46:22 INFO - "basedir": "/builds/slave/test", 12:46:22 INFO - "buildnumber": 254, 12:46:22 INFO - "slavename": "talos-linux64-ix-027", 12:46:22 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:46:22 INFO - "platform": "ubuntu64_hw", 12:46:22 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 12:46:22 INFO - "branch": "mozilla-beta", 12:46:22 INFO - "repo_path": "releases/mozilla-beta", 12:46:22 INFO - "revision": "65b9f5cea747a0815157758fb7c4a837910dac8f", 12:46:22 INFO - "stage_platform": "linux64-pgo", 12:46:22 INFO - "builduid": "a7e0d25f8cf9436283dd84e57c31c350", 12:46:22 INFO - "slavebuilddir": "test" 12:46:22 INFO - } 12:46:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 12:46:22 INFO - Running post-action listener: _resource_record_post_action 12:46:22 INFO - [mozharness: 2016-09-22 19:46:22.406457Z] Finished read-buildbot-config step (success) 12:46:22 INFO - [mozharness: 2016-09-22 19:46:22.406546Z] Running download-and-extract step. 12:46:22 INFO - Running pre-action listener: _resource_record_pre_action 12:46:22 INFO - Running main action method: download_and_extract 12:46:22 INFO - mkdir: /builds/slave/test/build/tests 12:46: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')]} 12:46:22 INFO - https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:46:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:46:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:46:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:46:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:46:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:46:22 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:46:22 INFO - retry: Failed, sleeping 30 seconds before retrying 12:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:46:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:46:52 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:46:52 INFO - retry: Failed, sleeping 60 seconds before retrying 12:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:47:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:47:52 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:47:52 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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! 12:47:52 INFO - Caught exception: HTTP Error 404: Not Found 12:47:52 INFO - Caught exception: HTTP Error 404: Not Found 12:47:52 INFO - Caught exception: HTTP Error 404: Not Found 12:47:52 INFO - trying https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:47:52 INFO - Downloading https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:47:53 INFO - Downloaded 1327 bytes. 12:47:53 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 12:47:53 INFO - Using the following test package requirements: 12:47:53 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:47:53 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 12:47:53 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 12:47:53 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'jsshell-linux-x86_64.zip'], 12:47:53 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 12:47:53 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 12:47:53 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 12:47:53 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 12:47:53 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 12:47:53 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 12:47:53 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 12:47:53 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:53 INFO - https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:47:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:47:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:47:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:47:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:47:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:47:53 INFO - retry: Failed, sleeping 30 seconds before retrying 12:48:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:48:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:48:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:48:23 INFO - retry: Failed, sleeping 60 seconds before retrying 12:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:49:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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! 12:49:23 INFO - Caught exception: HTTP Error 404: Not Found 12:49:23 INFO - Caught exception: HTTP Error 404: Not Found 12:49:23 INFO - Caught exception: HTTP Error 404: Not Found 12:49:23 INFO - trying https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:23 INFO - Downloading https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:24 INFO - Downloaded 21771043 bytes. 12:49:24 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'] 12:49:24 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 12:49:25 INFO - Return code: 0 12:49:25 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 12:49: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')]} 12:49:25 INFO - https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:49:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:49:25 INFO - retry: Failed, sleeping 30 seconds before retrying 12:49:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:49:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:49:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:49:55 INFO - retry: Failed, sleeping 60 seconds before retrying 12:50:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:50:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:50:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:50:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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! 12:50:55 INFO - Caught exception: HTTP Error 404: Not Found 12:50:55 INFO - Caught exception: HTTP Error 404: Not Found 12:50:55 INFO - Caught exception: HTTP Error 404: Not Found 12:50:55 INFO - trying https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 12:50:55 INFO - Downloading https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:50:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:50:56 INFO - Downloaded 21771043 bytes. 12:50:56 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'] 12:50:56 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 12:50:57 INFO - Return code: 0 12:50:57 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')]} 12:50:57 INFO - https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:50:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:50:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:50:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:50:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:50:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:50:57 INFO - retry: Failed, sleeping 30 seconds before retrying 12:51:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:51:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:51:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:51:27 INFO - retry: Failed, sleeping 60 seconds before retrying 12:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:52:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:52:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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! 12:52:27 INFO - Caught exception: HTTP Error 404: Not Found 12:52:27 INFO - Caught exception: HTTP Error 404: Not Found 12:52:27 INFO - Caught exception: HTTP Error 404: Not Found 12:52:27 INFO - trying https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 12:52:27 INFO - Downloading https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:28 INFO - Downloaded 14413740 bytes. 12:52:28 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'] 12:52:28 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 12:52:28 INFO - Return code: 0 12:52:28 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')]} 12:52:28 INFO - https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:52:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 12:52:28 INFO - retry: Failed, sleeping 30 seconds before retrying 12:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:52:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:52:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 12:52:58 INFO - retry: Failed, sleeping 60 seconds before retrying 12:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:53:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:53:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 12:53:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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! 12:53:58 INFO - Caught exception: HTTP Error 404: Not Found 12:53:58 INFO - Caught exception: HTTP Error 404: Not Found 12:53:58 INFO - Caught exception: HTTP Error 404: Not Found 12:53:58 INFO - trying https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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 12:54:00 INFO - Downloaded 56792508 bytes. 12:54:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:54:00 INFO - mkdir: /builds/slave/test/properties 12:54:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:54:00 INFO - Writing to file /builds/slave/test/properties/build_url 12:54:00 INFO - Contents: 12:54:00 INFO - build_url:https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 12:54:00 INFO - Running post-action listener: _resource_record_post_action 12:54:00 INFO - Running post-action listener: set_extra_try_arguments 12:54:00 INFO - [mozharness: 2016-09-22 19:54:00.704660Z] Finished download-and-extract step (success) 12:54:00 INFO - [mozharness: 2016-09-22 19:54:00.704894Z] Running populate-webroot step. 12:54:00 INFO - Running pre-action listener: _resource_record_pre_action 12:54:00 INFO - Running main action method: populate_webroot 12:54:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:54:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:54:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:54:00 INFO - '--tppagecycles', 12:54:00 INFO - '7'], 12:54:00 INFO - 'tests': ['tsvgm']}}, 12:54:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:54:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:54:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:54:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:54:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:54:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:54:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['damp', 'tps']}, 12:54:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'tests': ['damp', 'tps']}, 12:54:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['dromaeo_dom']}, 12:54:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:54:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['basic_compositor_video']}, 12:54:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:54:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'other-e10s': {'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['a11yr', 12:54:00 INFO - 'ts_paint', 12:54:00 INFO - 'tpaint', 12:54:00 INFO - 'sessionrestore', 12:54:00 INFO - 'sessionrestore_no_auto_restore', 12:54:00 INFO - 'tabpaint']}, 12:54:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['tsvgx', 12:54:00 INFO - 'tsvgr_opacity', 12:54:00 INFO - 'tart', 12:54:00 INFO - 'tscrollx', 12:54:00 INFO - 'cart']}, 12:54:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:54:00 INFO - 'tsvgr_opacity', 12:54:00 INFO - 'tart', 12:54:00 INFO - 'tscrollx', 12:54:00 INFO - 'cart']}, 12:54:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'talos_options': ['--disable-e10s'], 12:54:00 INFO - 'tests': ['tp5o']}, 12:54:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'tests': ['tp5o']}, 12:54:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'talos_options': ['--disable-e10s', 12:54:00 INFO - '--xperf_path', 12:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:54:00 INFO - 'tests': ['tp5n']}, 12:54:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:54:00 INFO - 'talos_options': ['--xperf_path', 12:54:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:54:00 INFO - 'tests': ['tp5n']}}, 12:54:00 INFO - 'talos.zip': {'path': '', 12:54:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:54:00 INFO - Running post-action listener: _resource_record_post_action 12:54:00 INFO - [mozharness: 2016-09-22 19:54:00.721212Z] Finished populate-webroot step (success) 12:54:00 INFO - [mozharness: 2016-09-22 19:54:00.721302Z] Running create-virtualenv step. 12:54:00 INFO - Running pre-action listener: _resource_record_pre_action 12:54:00 INFO - Running main action method: create_virtualenv 12:54:00 INFO - Creating virtualenv /builds/slave/test/build/venv 12:54:00 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 12:54:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:54:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:54:00 INFO - Using real prefix '/usr' 12:54:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:54:01 INFO - Installing distribute.............................................................................................................................................................................................done. 12:54:03 INFO - Installing pip.................done. 12:54:03 INFO - Return code: 0 12:54:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:54:03 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')]} 12:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:03 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')]} 12:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:03 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:03 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 12:54:03 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 12:54:03 INFO - Using env: {'DISPLAY': ':0', 12:54:03 INFO - 'HOME': '/home/cltbld', 12:54:03 INFO - 'LANG': 'en_US.UTF-8', 12:54:03 INFO - 'LANGUAGE': 'en_US:en', 12:54:03 INFO - 'LOGNAME': 'cltbld', 12:54:03 INFO - 'MAIL': '/var/mail/cltbld', 12:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:03 INFO - 'MOZ_NO_REMOTE': '1', 12:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:03 INFO - 'NO_EM_RESTART': '1', 12:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:03 INFO - 'PWD': '/builds/slave/test', 12:54:03 INFO - 'SHELL': '/bin/bash', 12:54:03 INFO - 'SHLVL': '1', 12:54:03 INFO - 'TERM': 'linux', 12:54:03 INFO - 'TMOUT': '86400', 12:54:03 INFO - 'USER': 'cltbld', 12:54:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:03 INFO - '_': '/tools/buildbot/bin/python'} 12:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:03 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 12:54:03 INFO - Cleaning up... 12:54:03 INFO - Return code: 0 12:54:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:54:03 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')]} 12:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:03 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')]} 12:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:03 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:03 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 12:54:03 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 12:54:03 INFO - Using env: {'DISPLAY': ':0', 12:54:03 INFO - 'HOME': '/home/cltbld', 12:54:03 INFO - 'LANG': 'en_US.UTF-8', 12:54:03 INFO - 'LANGUAGE': 'en_US:en', 12:54:03 INFO - 'LOGNAME': 'cltbld', 12:54:03 INFO - 'MAIL': '/var/mail/cltbld', 12:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:03 INFO - 'MOZ_NO_REMOTE': '1', 12:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:03 INFO - 'NO_EM_RESTART': '1', 12:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:03 INFO - 'PWD': '/builds/slave/test', 12:54:03 INFO - 'SHELL': '/bin/bash', 12:54:03 INFO - 'SHLVL': '1', 12:54:03 INFO - 'TERM': 'linux', 12:54:03 INFO - 'TMOUT': '86400', 12:54:03 INFO - 'USER': 'cltbld', 12:54:03 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:03 INFO - '_': '/tools/buildbot/bin/python'} 12:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:03 INFO - Downloading/unpacking psutil>=3.1.1 12:54:03 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 12:54:03 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 12:54:03 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 12:54:03 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 12:54:03 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 12:54:03 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 12:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:54:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:54:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:54:05 INFO - Installing collected packages: psutil 12:54:05 INFO - Running setup.py install for psutil 12:54:05 INFO - building 'psutil._psutil_linux' extension 12:54:05 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 12:54:05 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 12:54:05 INFO - building 'psutil._psutil_posix' extension 12:54:05 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 12:54:06 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 12:54:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:54:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:54:06 INFO - Successfully installed psutil 12:54:06 INFO - Cleaning up... 12:54:06 INFO - Return code: 0 12:54:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:54:06 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')]} 12:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:06 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')]} 12:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:06 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:06 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 12:54:06 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 12:54:06 INFO - Using env: {'DISPLAY': ':0', 12:54:06 INFO - 'HOME': '/home/cltbld', 12:54:06 INFO - 'LANG': 'en_US.UTF-8', 12:54:06 INFO - 'LANGUAGE': 'en_US:en', 12:54:06 INFO - 'LOGNAME': 'cltbld', 12:54:06 INFO - 'MAIL': '/var/mail/cltbld', 12:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:06 INFO - 'MOZ_NO_REMOTE': '1', 12:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:06 INFO - 'NO_EM_RESTART': '1', 12:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:06 INFO - 'PWD': '/builds/slave/test', 12:54:06 INFO - 'SHELL': '/bin/bash', 12:54:06 INFO - 'SHLVL': '1', 12:54:06 INFO - 'TERM': 'linux', 12:54:06 INFO - 'TMOUT': '86400', 12:54:06 INFO - 'USER': 'cltbld', 12:54:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:06 INFO - '_': '/tools/buildbot/bin/python'} 12:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:54:06 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 12:54:06 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 12:54:06 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 12:54:06 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 12:54:06 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 12:54:06 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 12:54:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:54:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:54:08 INFO - Installing collected packages: mozsystemmonitor 12:54:08 INFO - Running setup.py install for mozsystemmonitor 12:54:08 INFO - Successfully installed mozsystemmonitor 12:54:08 INFO - Cleaning up... 12:54:08 INFO - Return code: 0 12:54:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:54:08 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')]} 12:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:08 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')]} 12:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:08 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:08 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 12:54:08 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 12:54:08 INFO - Using env: {'DISPLAY': ':0', 12:54:08 INFO - 'HOME': '/home/cltbld', 12:54:08 INFO - 'LANG': 'en_US.UTF-8', 12:54:08 INFO - 'LANGUAGE': 'en_US:en', 12:54:08 INFO - 'LOGNAME': 'cltbld', 12:54:08 INFO - 'MAIL': '/var/mail/cltbld', 12:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:08 INFO - 'MOZ_NO_REMOTE': '1', 12:54:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:08 INFO - 'NO_EM_RESTART': '1', 12:54:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:08 INFO - 'PWD': '/builds/slave/test', 12:54:08 INFO - 'SHELL': '/bin/bash', 12:54:08 INFO - 'SHLVL': '1', 12:54:08 INFO - 'TERM': 'linux', 12:54:08 INFO - 'TMOUT': '86400', 12:54:08 INFO - 'USER': 'cltbld', 12:54:08 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:08 INFO - '_': '/tools/buildbot/bin/python'} 12:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:08 INFO - Downloading/unpacking jsonschema==2.5.1 12:54:08 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 12:54:08 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 12:54:08 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 12:54:08 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 12:54:08 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 12:54:08 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 12:54:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:54:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:54:10 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 12:54:10 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 12:54:10 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 12:54:10 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 12:54:10 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 12:54:10 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 12:54:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:54:10 INFO - warning: no files found matching '*.txt' 12:54:10 INFO - no previously-included directories found matching 'build' 12:54:10 INFO - no previously-included directories found matching 'dist' 12:54:10 INFO - no previously-included directories found matching '.git*' 12:54:10 INFO - Installing collected packages: jsonschema, functools32 12:54:10 INFO - Running setup.py install for functools32 12:54:11 INFO - warning: no files found matching '*.txt' 12:54:11 INFO - no previously-included directories found matching 'build' 12:54:11 INFO - no previously-included directories found matching 'dist' 12:54:11 INFO - no previously-included directories found matching '.git*' 12:54:11 INFO - Successfully installed jsonschema functools32 12:54:11 INFO - Cleaning up... 12:54:11 INFO - Return code: 0 12:54:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:54:11 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')]} 12:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:11 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')]} 12:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:11 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:11 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 12:54:11 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 12:54:11 INFO - Using env: {'DISPLAY': ':0', 12:54:11 INFO - 'HOME': '/home/cltbld', 12:54:11 INFO - 'LANG': 'en_US.UTF-8', 12:54:11 INFO - 'LANGUAGE': 'en_US:en', 12:54:11 INFO - 'LOGNAME': 'cltbld', 12:54:11 INFO - 'MAIL': '/var/mail/cltbld', 12:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:11 INFO - 'MOZ_NO_REMOTE': '1', 12:54:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:11 INFO - 'NO_EM_RESTART': '1', 12:54:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:11 INFO - 'PWD': '/builds/slave/test', 12:54:11 INFO - 'SHELL': '/bin/bash', 12:54:11 INFO - 'SHLVL': '1', 12:54:11 INFO - 'TERM': 'linux', 12:54:11 INFO - 'TMOUT': '86400', 12:54:11 INFO - 'USER': 'cltbld', 12:54:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:11 INFO - '_': '/tools/buildbot/bin/python'} 12:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:54:11 INFO - Cleaning up... 12:54:11 INFO - Return code: 0 12:54:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:54:11 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')]} 12:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:11 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')]} 12:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:11 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:11 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 12:54:11 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 12:54:11 INFO - Using env: {'DISPLAY': ':0', 12:54:11 INFO - 'HOME': '/home/cltbld', 12:54:11 INFO - 'LANG': 'en_US.UTF-8', 12:54:11 INFO - 'LANGUAGE': 'en_US:en', 12:54:11 INFO - 'LOGNAME': 'cltbld', 12:54:11 INFO - 'MAIL': '/var/mail/cltbld', 12:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:11 INFO - 'MOZ_NO_REMOTE': '1', 12:54:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:11 INFO - 'NO_EM_RESTART': '1', 12:54:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:11 INFO - 'PWD': '/builds/slave/test', 12:54:11 INFO - 'SHELL': '/bin/bash', 12:54:11 INFO - 'SHLVL': '1', 12:54:11 INFO - 'TERM': 'linux', 12:54:11 INFO - 'TMOUT': '86400', 12:54:11 INFO - 'USER': 'cltbld', 12:54:11 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:11 INFO - '_': '/tools/buildbot/bin/python'} 12:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:11 INFO - Downloading/unpacking blobuploader==1.2.4 12:54:11 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 12:54:11 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 12:54:11 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 12:54:11 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 12:54:11 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 12:54:11 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 12:54:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:54:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:54:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:13 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 12:54:14 INFO - Downloading docopt-0.6.1.tar.gz 12:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:54:14 INFO - Installing collected packages: blobuploader, requests, docopt 12:54:14 INFO - Running setup.py install for blobuploader 12:54:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:54:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:54:14 INFO - Running setup.py install for requests 12:54:14 INFO - Running setup.py install for docopt 12:54:14 INFO - Successfully installed blobuploader requests docopt 12:54:14 INFO - Cleaning up... 12:54:14 INFO - Return code: 0 12:54:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:54:14 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')]} 12:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:14 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')]} 12:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:14 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:14 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 12:54:14 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 12:54:14 INFO - Using env: {'DISPLAY': ':0', 12:54:14 INFO - 'HOME': '/home/cltbld', 12:54:14 INFO - 'LANG': 'en_US.UTF-8', 12:54:14 INFO - 'LANGUAGE': 'en_US:en', 12:54:14 INFO - 'LOGNAME': 'cltbld', 12:54:14 INFO - 'MAIL': '/var/mail/cltbld', 12:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:14 INFO - 'MOZ_NO_REMOTE': '1', 12:54:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:14 INFO - 'NO_EM_RESTART': '1', 12:54:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:14 INFO - 'PWD': '/builds/slave/test', 12:54:14 INFO - 'SHELL': '/bin/bash', 12:54:14 INFO - 'SHLVL': '1', 12:54:14 INFO - 'TERM': 'linux', 12:54:14 INFO - 'TMOUT': '86400', 12:54:14 INFO - 'USER': 'cltbld', 12:54:14 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:14 INFO - '_': '/tools/buildbot/bin/python'} 12:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:54:15 INFO - Running setup.py (path:/tmp/pip-6fsVSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:54:15 INFO - Running setup.py (path:/tmp/pip-9FbGAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:54:15 INFO - Running setup.py (path:/tmp/pip-wPHCP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:54:15 INFO - Running setup.py (path:/tmp/pip-rLpgEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:54:15 INFO - Running setup.py (path:/tmp/pip-YGbx5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:54:15 INFO - Running setup.py (path:/tmp/pip-m5X7nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:54:15 INFO - Running setup.py (path:/tmp/pip-1S8rMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:54:15 INFO - Running setup.py (path:/tmp/pip-k0R4k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:54:15 INFO - Running setup.py (path:/tmp/pip-0EaE4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:54:15 INFO - Running setup.py (path:/tmp/pip-UxhcpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:54:15 INFO - Running setup.py (path:/tmp/pip-8CaP6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:54:15 INFO - Running setup.py (path:/tmp/pip-7eN7Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:54:15 INFO - Running setup.py (path:/tmp/pip-LGrd9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:54:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:54:15 INFO - Running setup.py (path:/tmp/pip-Id9SuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:54:16 INFO - Running setup.py (path:/tmp/pip-d87eon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:54:16 INFO - Running setup.py (path:/tmp/pip-ht8MQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:54:16 INFO - Running setup.py (path:/tmp/pip-NxJb6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:54:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:54:16 INFO - Running setup.py install for manifestparser 12:54:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:54:16 INFO - Running setup.py install for mozcrash 12:54:16 INFO - Running setup.py install for mozdebug 12:54:16 INFO - Running setup.py install for mozdevice 12:54:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:54:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:54:16 INFO - Running setup.py install for mozfile 12:54:16 INFO - Running setup.py install for mozhttpd 12:54:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:54:16 INFO - Running setup.py install for mozinfo 12:54:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:54:16 INFO - Running setup.py install for mozInstall 12:54:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Running setup.py install for mozleak 12:54:17 INFO - Running setup.py install for mozlog 12:54:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Running setup.py install for moznetwork 12:54:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Running setup.py install for mozprocess 12:54:17 INFO - Running setup.py install for mozprofile 12:54:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Running setup.py install for mozrunner 12:54:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:54:17 INFO - Running setup.py install for mozscreenshot 12:54:17 INFO - Running setup.py install for moztest 12:54:17 INFO - Running setup.py install for mozversion 12:54:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:54:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:54:18 INFO - Cleaning up... 12:54:18 INFO - Return code: 0 12:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:54:18 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')]} 12:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:18 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')]} 12:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:18 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:18 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 12:54:18 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 12:54:18 INFO - Using env: {'DISPLAY': ':0', 12:54:18 INFO - 'HOME': '/home/cltbld', 12:54:18 INFO - 'LANG': 'en_US.UTF-8', 12:54:18 INFO - 'LANGUAGE': 'en_US:en', 12:54:18 INFO - 'LOGNAME': 'cltbld', 12:54:18 INFO - 'MAIL': '/var/mail/cltbld', 12:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:18 INFO - 'MOZ_NO_REMOTE': '1', 12:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:18 INFO - 'NO_EM_RESTART': '1', 12:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:18 INFO - 'PWD': '/builds/slave/test', 12:54:18 INFO - 'SHELL': '/bin/bash', 12:54:18 INFO - 'SHLVL': '1', 12:54:18 INFO - 'TERM': 'linux', 12:54:18 INFO - 'TMOUT': '86400', 12:54:18 INFO - 'USER': 'cltbld', 12:54:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:18 INFO - '_': '/tools/buildbot/bin/python'} 12:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:54:18 INFO - Running setup.py (path:/tmp/pip-J0JXKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:54:18 INFO - Running setup.py (path:/tmp/pip-RzuiVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:54:18 INFO - Running setup.py (path:/tmp/pip-uCxbv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:54:18 INFO - Running setup.py (path:/tmp/pip-5YP9Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:54:18 INFO - Running setup.py (path:/tmp/pip-44m2iS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:54:18 INFO - Running setup.py (path:/tmp/pip-YTu2um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:54:18 INFO - Running setup.py (path:/tmp/pip-dmXrYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:54:18 INFO - Running setup.py (path:/tmp/pip-zVQlUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:54:18 INFO - Running setup.py (path:/tmp/pip-XmSPmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:54:18 INFO - Running setup.py (path:/tmp/pip-mbwYja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:54:18 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)) 12:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:54:19 INFO - Running setup.py (path:/tmp/pip-AITgWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:54:19 INFO - Running setup.py (path:/tmp/pip-yB_l0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:54:19 INFO - Running setup.py (path:/tmp/pip-eATOLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:54:19 INFO - Running setup.py (path:/tmp/pip-l6taA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:54:19 INFO - Running setup.py (path:/tmp/pip-p410P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:54:19 INFO - Running setup.py (path:/tmp/pip-4e3Bie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:54:19 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)) 12:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:54:19 INFO - Running setup.py (path:/tmp/pip-x5HSR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:54:19 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)) 12:54:19 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)) 12:54:19 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)) 12:54:19 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)) 12:54:19 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)) 12:54:19 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)) 12:54:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:54:19 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 12:54:19 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 12:54:19 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 12:54:19 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 12:54:19 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 12:54:19 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 12:54:21 INFO - Downloading blessings-1.6.tar.gz 12:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:54:21 INFO - Installing collected packages: blessings 12:54:21 INFO - Running setup.py install for blessings 12:54:21 INFO - Successfully installed blessings 12:54:21 INFO - Cleaning up... 12:54:21 INFO - Return code: 0 12:54:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:54:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:54:21 INFO - Reading from file tmpfile_stdout 12:54:21 INFO - Current package versions: 12:54:21 INFO - argparse == 1.2.1 12:54:21 INFO - blessings == 1.6 12:54:21 INFO - blobuploader == 1.2.4 12:54:21 INFO - docopt == 0.6.1 12:54:21 INFO - functools32 == 3.2.3-2 12:54:21 INFO - jsonschema == 2.5.1 12:54:21 INFO - manifestparser == 1.1 12:54:21 INFO - mozInstall == 1.12 12:54:21 INFO - mozcrash == 0.17 12:54:21 INFO - mozdebug == 0.1 12:54:21 INFO - mozdevice == 0.48 12:54:21 INFO - mozfile == 1.2 12:54:21 INFO - mozhttpd == 0.7 12:54:21 INFO - mozinfo == 0.9 12:54:21 INFO - mozleak == 0.1 12:54:21 INFO - mozlog == 3.3 12:54:21 INFO - moznetwork == 0.27 12:54:21 INFO - mozprocess == 0.23 12:54:21 INFO - mozprofile == 0.28 12:54:21 INFO - mozrunner == 6.12 12:54:21 INFO - mozscreenshot == 0.1 12:54:21 INFO - mozsystemmonitor == 0.3 12:54:21 INFO - moztest == 0.7 12:54:21 INFO - mozversion == 1.4 12:54:21 INFO - psutil == 3.1.1 12:54:21 INFO - requests == 1.2.3 12:54:21 INFO - wsgiref == 0.1.2 12:54:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:54:21 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')]} 12:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:21 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')]} 12:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54:21 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 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54:21 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 12:54:21 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 12:54:21 INFO - Using env: {'DISPLAY': ':0', 12:54:21 INFO - 'HOME': '/home/cltbld', 12:54:21 INFO - 'LANG': 'en_US.UTF-8', 12:54:21 INFO - 'LANGUAGE': 'en_US:en', 12:54:21 INFO - 'LOGNAME': 'cltbld', 12:54:21 INFO - 'MAIL': '/var/mail/cltbld', 12:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:21 INFO - 'MOZ_NO_REMOTE': '1', 12:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:21 INFO - 'NO_EM_RESTART': '1', 12:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:21 INFO - 'PWD': '/builds/slave/test', 12:54:21 INFO - 'SHELL': '/bin/bash', 12:54:21 INFO - 'SHLVL': '1', 12:54:21 INFO - 'TERM': 'linux', 12:54:21 INFO - 'TMOUT': '86400', 12:54:21 INFO - 'USER': 'cltbld', 12:54:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:21 INFO - '_': '/tools/buildbot/bin/python'} 12:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 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)) 12:54:22 INFO - Cleaning up... 12:54:22 INFO - Return code: 0 12:54:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:54: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')]} 12:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54: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')]} 12:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:54: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb49030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, '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': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', '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 12:54: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', 'jsonschema'] in /builds/slave/test/build 12:54: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 jsonschema 12:54:22 INFO - Using env: {'DISPLAY': ':0', 12:54:22 INFO - 'HOME': '/home/cltbld', 12:54:22 INFO - 'LANG': 'en_US.UTF-8', 12:54:22 INFO - 'LANGUAGE': 'en_US:en', 12:54:22 INFO - 'LOGNAME': 'cltbld', 12:54:22 INFO - 'MAIL': '/var/mail/cltbld', 12:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:22 INFO - 'MOZ_NO_REMOTE': '1', 12:54:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:22 INFO - 'NO_EM_RESTART': '1', 12:54:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:22 INFO - 'PWD': '/builds/slave/test', 12:54:22 INFO - 'SHELL': '/bin/bash', 12:54:22 INFO - 'SHLVL': '1', 12:54:22 INFO - 'TERM': 'linux', 12:54:22 INFO - 'TMOUT': '86400', 12:54:22 INFO - 'USER': 'cltbld', 12:54:22 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:22 INFO - '_': '/tools/buildbot/bin/python'} 12:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:54:22 INFO - Cleaning up... 12:54:22 INFO - Return code: 0 12:54:22 INFO - Running post-action listener: _resource_record_post_action 12:54:22 INFO - Running post-action listener: _start_resource_monitoring 12:54:22 INFO - Starting resource monitoring. 12:54:22 INFO - [mozharness: 2016-09-22 19:54:22.359877Z] Finished create-virtualenv step (success) 12:54:22 INFO - [mozharness: 2016-09-22 19:54:22.360337Z] Running install step. 12:54:22 INFO - Running pre-action listener: _resource_record_pre_action 12:54:22 INFO - Running main action method: install 12:54:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:54:22 INFO - Reading from file tmpfile_stdout 12:54:22 INFO - Detecting whether we're running mozinstall >=1.0... 12:54:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:54:22 INFO - Reading from file tmpfile_stdout 12:54:22 INFO - Output received: 12:54:22 INFO - Usage: mozinstall [options] installer 12:54:22 INFO - Options: 12:54:22 INFO - -h, --help show this help message and exit 12:54:22 INFO - -d DEST, --destination=DEST 12:54:22 INFO - Directory to install application into. [default: 12:54:22 INFO - "/builds/slave/test"] 12:54:22 INFO - --app=APP Application being installed. [default: firefox] 12:54:22 INFO - mkdir: /builds/slave/test/build/application 12:54:22 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'] 12:54:22 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 12:54:36 INFO - Reading from file tmpfile_stdout 12:54:36 INFO - Output received: 12:54:36 INFO - /builds/slave/test/build/application/firefox/firefox 12:54:36 INFO - Running post-action listener: _resource_record_post_action 12:54:36 INFO - [mozharness: 2016-09-22 19:54:36.667768Z] Finished install step (success) 12:54:36 INFO - [mozharness: 2016-09-22 19:54:36.668046Z] Running run-tests step. 12:54:36 INFO - Running pre-action listener: _resource_record_pre_action 12:54:36 INFO - Running main action method: run_tests 12:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:54:36 INFO - Python 2.7.3 12:54:36 INFO - Return code: 0 12:54:36 INFO - grabbing minidump binary from tooltool 12:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:54:36 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 0xe083b0>, '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 0xe092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe09740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:54:36 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 12:54:36 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 12:54:36 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 12:54:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:54:36 INFO - Return code: 0 12:54:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:54:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:54:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:54:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:54:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:54:36 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/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 12:54:36 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/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 12:54:36 INFO - Using env: {'DISPLAY': ':0', 12:54:36 INFO - 'HOME': '/home/cltbld', 12:54:36 INFO - 'LANG': 'en_US.UTF-8', 12:54:36 INFO - 'LANGUAGE': 'en_US:en', 12:54:36 INFO - 'LOGNAME': 'cltbld', 12:54:36 INFO - 'MAIL': '/var/mail/cltbld', 12:54:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:54:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:54:36 INFO - 'MOZ_NO_REMOTE': '1', 12:54:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:54:36 INFO - 'NO_EM_RESTART': '1', 12:54:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:54:36 INFO - 'PWD': '/builds/slave/test', 12:54:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:54:36 INFO - 'SHELL': '/bin/bash', 12:54:36 INFO - 'SHLVL': '1', 12:54:36 INFO - 'TERM': 'linux', 12:54:36 INFO - 'TMOUT': '86400', 12:54:36 INFO - 'USER': 'cltbld', 12:54:36 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015', 12:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:54:36 INFO - '_': '/tools/buildbot/bin/python'} 12:54:36 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/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 12:54:36 INFO - mozversion application_buildid: 20160922094859 12:54:36 INFO - mozversion application_changeset: 65b9f5cea747a0815157758fb7c4a837910dac8f 12:54:36 INFO - mozversion application_display_name: Firefox 12:54:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:54:36 INFO - mozversion application_name: Firefox 12:54:36 INFO - mozversion application_remotingname: firefox 12:54:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:54:36 INFO - mozversion application_vendor: Mozilla 12:54:36 INFO - mozversion application_version: 50.0 12:54:36 INFO - mozversion platform_buildid: 20160922094859 12:54:36 INFO - mozversion platform_changeset: 65b9f5cea747a0815157758fb7c4a837910dac8f 12:54:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:54:36 INFO - mozversion platform_version: 50.0 12:54:36 INFO - using testdate: 1474574076 12:54:36 INFO - actual date: 1474574076 12:54:36 INFO - starting webserver on 'localhost:40993' 12:54:36 INFO - SUITE-START | Running 2 tests 12:54:36 INFO - TEST-START | dromaeo_css 12:54:36 INFO - operating with platform_type : linux_ 12:54:36 INFO - Initialising browser for dromaeo_css test... 12:54:36 INFO - TEST-INFO | started process 29039 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSqREKq/profile http://localhost:40993/getInfo.html) 12:54:40 INFO - PROCESS | 29039 | __metrics Screen width/height:1600/1200 12:54:40 INFO - PROCESS | 29039 | colorDepth:24 12:54:40 INFO - PROCESS | 29039 | Browser inner width/height: 1024/697 12:54:40 INFO - PROCESS | 29039 | __metrics 12:54:41 INFO - PROCESS | 29039 | 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" 12:54:41 INFO - PROCESS | 29039 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:54:42 INFO - TEST-INFO | 29039: exit 0 12:54:42 INFO - Browser initialized. 12:54:42 INFO - Running cycle 1/1 for dromaeo_css test... 12:54:42 INFO - TEST-INFO | started process 29129 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSqREKq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:54 INFO - PROCESS | 29129 | RSS: Main: 144035840 12:54:54 INFO - PROCESS | 29129 | 12:55:45 INFO - PROCESS | 29129 | 1474574145081 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] 12:57:54 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40993/tests/dromaeo/cssquery-ext.html) 12:57:55 INFO - PROCESS | 29129 | RSS: Main: 275316736 12:57:55 INFO - PROCESS | 29129 | 13:00:57 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-ext.html (next: http://localhost:40993/tests/dromaeo/cssquery-jquery.html) 13:00:58 INFO - PROCESS | 29129 | RSS: Main: 232116224 13:00:58 INFO - PROCESS | 29129 | 13:04:00 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40993/tests/dromaeo/cssquery-mootools.html) 13:04:00 INFO - PROCESS | 29129 | RSS: Main: 176447488 13:04:00 INFO - PROCESS | 29129 | 13:07:02 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40993/tests/dromaeo/cssquery-prototype.html) 13:07:03 INFO - PROCESS | 29129 | RSS: Main: 222617600 13:07:03 INFO - PROCESS | 29129 | 13:10:05 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40993/tests/dromaeo/cssquery-yui.html) 13:10:06 INFO - PROCESS | 29129 | RSS: Main: 175824896 13:10:06 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | Cycle 1(1): loaded http://localhost:40993/tests/dromaeo/cssquery-yui.html (next: http://localhost:40993/tests/dromaeo/cssquery-dojo.html) 13:13:08 INFO - PROCESS | 29129 | RSS: Main: 265740288 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | __start_tp_report 13:13:08 INFO - PROCESS | 29129 | _x_x_mozilla_page_load 13:13:08 INFO - PROCESS | 29129 | _x_x_mozilla_page_load_details 13:13:08 INFO - PROCESS | 29129 | |i|pagename|runs| 13:13:08 INFO - PROCESS | 29129 | |0;dojo.html;3230.1924518870283;3238.919027024324;3267.411865864145;3288.588926384202;3301.1747063234193;5611.410801865802;5696.231008813819;5706.457886500786;5706.885862282752;5712.714364281785;5672.06410942194;5717.085266357383;5732.5987180897355;5737.426257374271;5748.367679555246;4973.378327709036;5074.086664400406;5177.249298851673;5179.119549676564;5219.034478621448;5170.457102004292;5213.947860521384;5215.530602245816;5218.295530103444;5219.478052194788;5096.082705113096;5138.948610513903;5177.87055323618;5190.429052804188;5201.765920533585;5241.42344342122;5244.9737751310995;5248.291480650101;5252.107141785906;5282.894342113174;5012.8496145115705;5070;5126.128558145124;5179;5187.533122018998;5563.443655634444;5563.693996830173;5565.582581306418;5577.274954255961;5582.581306401996;6078.331383547807;6083.513318934474;6088.604240724383;6091.725872335755;6097;5854.941450585482;6005.549583781234;6037.54718396122;6056.576039677225;6145.508359331243;5678.545716342683;5682.119271512922;5700.971495142539;5705.429457054262;5727.198192253088;6217.0052791553135;6221.782237621752;6242.937570624235;6248.5313601480075;6260.749570017239;5785.103308987113;5902.055671092605;5960.0761881908375;5965.135055417027;6051.515878729691;6120.14317995481;6123.142760013601;6124.234470691164;6125.663088530174;6131.294901086432;6067.908981365283;6077.1491991121275;6078.452939235402;6079.604825686405;6108.236470441195;5777.133429985536;5931.466168044896;5950.613210141327;5988.311344195473;6086.878262434727;4620.283856002325;4651.279051746985;4655.2784318430695;4655.790489427984;4689.038747056845;4148.087420767426;4157.605027522381;4160.18876319121;4178.35235538492;4186.832526698959;5578;5701.287339082614;5736.655800651891;5791.391903850119;5871.4715675589405;6199.907001394983;6201.224846894138;6202.038684003986;6206.968965155235;6212.4408803207925;5857.648541087463;5860.794872179454;5863.061910094364;5871.647701137946;5902.527797776168;5157.149070403341;5211.1662134059125;5223.973880130575;5248.396434410015;5339.252504649352;6331.841703957346;6333.841653958688;6341.207349081365;6352.047192921099;6354.650494222862;6055.485283750754;6073.544484163617;6089.604175728564;6118.021116621319;6121.969390153021;6106.969465152646;6136.570440069152;6240.687965601793;6300.464460520724;6330.177076979963;6049.969750151221;6056.36408177135;6066.878662426815;6067.180930574316;6080.209572755513;6143.96928015357;6154.953657878082;6166.321704612399;6172.444479996642;6176.938230617637;5732.770689172553;5845.649261044351;5924.555658325557;5939.4357536034;6008.218931538872;5907.143464197581;5948.13752005948;5951.345352011189;5987.461128498456;6008.969955150196;5847.649141051551;5913.674747888907;5964.135200396005;5971.641701497924;5980.671063091571;5781.566382521243;5924.851878703067;5947.434993675594;5984;5996.910046349395;5889.793857214977;5893.705314734332;5901.7639294427745;5914.526837853047;5926.436988486087 13:13:08 INFO - PROCESS | 29129 | |1;ext.html;582.9533637309015;638.8466767975685;655.7508146904174;659.5943494750729;672.2672287206949;33979.83010084949;37366.626333736654;37432.812835935816;37555;37559;48031.51968480314;49002.50997490024;49012;52554;52609.210861837106;9243.722688319345;9409.341346105775;9426.481543515103;9431.292653051014;9434.669786557472;8661.393702440831;8864.069272726367;9305.767355816124;9391.107844754772;9391.154796068353;8998.055204203563;9043.954780226122;11237.269577477478;11487.425628718573;11494.482748276307;11209;11254;11277;11281.492332845;11319.717007074807;11258.155638327158;11322.377269250188;11330.773384532264;11339.829902551468;11343.43282835855;46133;46515.069698606174;46575.30137047947;46675.29987050197;46786.53213467856;3977.8607748728946;4087.89780255493;4113.053997580573;4151.543330233671;4359.564043595615;34381.31237375263;34453.13867153316;34928;35223;36833;4691.601213896823;4747.264174053027;4786.856394308176;4884.19410797218;4942.950570494285;359910;359931;360209;360316;361413;64514.35485645178;65210.673946630915;65315.673421632586;65392;65536;40281.597184027785;40572.594274057476;40808.18383632311;43479.78260108743;43591.56408435875;20211.696824547642;20325;20389.388318350473;20406.897965510077;20442.28452004173;15703.68592628164;15769.68460630781;15812.92093539525;15820.68358632844;15831.762523562156;18842.15210315539;20693.68959465609;21196.47008824761;21344;21444.463888402915;7489.513181643176;7496.550206987489;7701.229877012366;7728.613569321511;7912.881306780288;960.9087136722001;1046.2937517175876;1063.5267306048888;1151.8560179977503;1154.5958914380067;34767.65232347644;35550.64449355525;35586.64413355833;35641.64358356383;35763.82118089393;1120.4117838135044;1178.9587364442202;1181.787278289916;1187.89902858258;1220.6399112261904;18321.63356732858;18351.35770248035;18423.72364414535;18455.354062608014;18510.259589616533;566099;569780;571701;571822;572327;64730.67634661891;65137.348626514075;65147.348526515074;65304.67347663327;65344;1347.5553067487706;1411.266141606359;1453.8328092269312;1455.3887367305756;1496.498672944554;33276.66723332736;35934.281314373045;36308.81845590755;36970.44544331783;36992.26015479622;55182.72408637931;59829;61536.69231653813;61572.38427615846;61615.38384616096;518056;523611;528342;528789;528914;40164.1967160661;45489.772551138354;45744.77127614341;45876;50478.49521504738;52085;52161;52194;52378.47621523736;52476.212856806414;42952.78523607362;44360.77819610881;44669.55330446654;45550;47033.52966470292;45335.54664453313;45907;46059;46186.50402128725;46705;43580;44261.33607995818;44483.777581111885;44661;44727.77636111798;37012.44481332728;40197.59802402055;40606;40819;44539.33191002073;29823;29996.400071998003;30430.391392172474;30599.694003059685;30859.537106943862 13:13:08 INFO - PROCESS | 29129 | |2;jquery.html;423.1705856521218;434.25429772315294;434.51551520055136;439.7757143856633;443.71158746814564;19175.424737257887;20713;20982.265620703278;21744.3691723121;21887.78112218881;19927.302544410948;20131.899340503314;20631.484212894684;20764.68852967203;20785;5209.817656382027;5479.698616576086;5483.451654834505;5484.862878428032;5487.039768040597;5262.605304602151;5339;5368.355797304329;5391.218273350361;5411.702356370398;5380.596455265852;5385.515303622673;5395.81114660987;5395.838124856241;5397.4062853086125;5393.4336894626085;5596.664200147984;5831.6209446386065;5857.121431785266;5960.910586341209;5727.312722473288;5774.653520788724;5795.594308398414;5986.5210783137245;6029.6382217066675;14241.78637320441;14571.344289506891;14575.271236438135;19459.4162175135;24716.876415617808;2413.613821788523;2452.705675318973;2452.742462041496;2590.3265151060673;2847.985760071186;10311.123554497877;10479.633212837514;10481;10691.518881650345;14448.205348705817;1997.719452867869;1997.820196182336;2041.693745938121;2067.6795096760025;2076.5223998480283;87448;89954.55022724844;90093;91453;93056.53471732666;11369;11646.184767066394;11767.176297659082;11811.940940295244;11827.467763950643;17252.482425527003;17252.741208881627;17286.567835804206;17288.740668889975;17296.135193240287;9038.63845446188;9159.49622770754;9209.539523023823;9211.63153473867;9344.813103737888;9038.63845446188;9065.546722663841;9079.45523268606;9278.72163835086;9296.30277729173;4871.926921096187;5005.124103281911;5048.823291184791;5282.498162674539;5287.57699384056;2102.4533621258583;2112.503561663008;2127.563849410867;2158.2014654577592;2225.2879078694987;953.909378609031;972.3873959405529;1026.8511065895402;1048.8793788714395;1073.307716522854;18222.0058809505;21061.26285580028;21150.788492114883;21202.363929082152;21297.78702212989;1020.3724709303873;1044.1698849414734;1080.443571560647;1108.4236197177422;1153.3425947210178;10676.786464270672;11619.128565357463;11674.591389301333;11737.471813768401;11748.941255293668;532770;535089;538068;542443;558194;94470.0552994475;95719.52140239254;96606.51696741612;96769;98921;1005.7938122684832;1045.137761346892;1073.8335557988523;1091.645215305029;1092.8907109289166;17562.82437175612;17806.910965445608;18083.457496275132;18317.267309307477;18581.628367432844;34813.25933703254;41213.96965075897;43494.56505434905;43754;44308.556914430446;55884;60312.396876032435;61415;61884.69057654863;63079.05381419374;7920.603969801602;8312.625931833203;8960.820783584422;9166.266698664223;9386.5776040077;13872.791908121184;14207.28963551839;14236.217008064656;14377.209253491159;14514.419423223375;12422;12440.75118497643;12480.875191248335;12549;12598.54328405952;12041.518339266331;12258.87741122613;12260.938695306466;12344.567940122053;12804.10371274002;12075.27548347102;12305.200161989442;12500;12733.236005839679;12739.93630031881;8283.50298982063;8430.241278284984;8598.957005214934;8753.255973242345;8822.2060014599;11092.944535277595;11484.770304594029;11850.466728997044;12028.338441385458;12360.876391235972 13:13:08 INFO - PROCESS | 29129 | |3;mootools.html;1396.8044473773666;1545.814502259729;1575.763635454682;1742.468547093136;1853.4810253129128;9182.219511341536;9622.134007939285;9640.614375424975;9973.800523989516;9981.950090249538;9773.413595184295;9894.653687120934;9900.702978910625;9909.603615855358;9911.653092141776;2462.4090218347555;2501.4246723253664;2510.698716154055;2512.1207577347964;2512.4221429071235;2434.7443518430578;2436.5979613363775;2443.315871555967;2443.9144629937955;2445.510897820434;2369.95260094798;2423.309356833303;2424.2848359733835;2431.465077682907;2432.3797431654903;2404.1705611563984;2424.1394305021727;2428.138011006085;2432.2946345559767;2437.9882348825217;2353.846999944998;2402.759724027601;2407.277816655014;2412.2039726890084;2412.517496500707;9395.107464790834;9572.712818615453;9703.902960970441;9723.46520941348;9770.218382529381;5479.232907392969;5742.885142297131;5744.626599271034;5753.424657534255;5759.740811663443;3573.446115852047;3640.1627625645983;3667.3215455140885;3670.376036073874;3673.669369756708;1170.7073231692077;1191.8093105103142;1193.665773583398;1231.513552146906;1246.993765031178;13834.100783449145;13935.930320348434;13959.371828267753;13966.790498142536;13990.790138147835;4480.686351955332;4688.132695451284;4750.358701575312;4813.013332266877;4832.540908613676;10795.622153224756;10795.784084318428;10797.892021079846;10804.78390432187;10804.837927431096;5563.332400111932;5584.078627026495;5589.888202235932;5606.299212598426;5612.5229355504425;4547.613452856544;4664.277037059262;4768.380110585602;5180.119379705459;5226.738663066832;4060.0864805419137;4137.234611596864;4160.271952408317;4162.812673429703;4261.893452663709;1214.4110106598293;1216.0332535634193;1224.2960297828783;1232.2298563397876;1233.85868072033;945.4516380499294;953.270747877874;1018.2770233134557;1080.5353697909818;1106.31961343773;6457.192850893638;6495.4803615710625;6612.669366531654;6711.6979735912;6824.488163387766;471.11900745605254;477.35556998053045;483.3209340876075;499.22121490474973;499.22370713540795;4456.799444024962;4602.125596136723;4605.792739326738;4732.5977291929885;4742.857714268577;11364.374959377148;11791.587294444653;12230.510779568896;12302.446389912475;12315;11639.825402618968;12123.151379403533;12147.392630368446;12180.086493513021;12192.939035304767;8934.927808663002;9114.817703646022;9121.133492318351;9125.817483650422;9126.406783559047;6614.371634694697;6640.83397915056;6651.600903945779;6787.592744435349;6825.829354266183;10755.946220268848;11315.71710707206;11325.660230193107;11327.943360283476;11420.600278990196;11333.716657083638;11374.658760237206;11458.369789661649;11459.770804583695;11512.769744605228;8669.393142479967;8802.471851688919;8860.911390886009;8863.601137948679;8900.42147260426;9233.538323083952;9440.197583205505;9454.669086582211;9464.952675236855;9465.384749991228;8409.453385529921;8481.575921203792;8568;8683.348227584102;8787.340949428946;8305.584720763816;8441.746747597583;8448.112948140602;8450.154984501502;8657.740267791976;7100.609466479394;7607.353374962969;7783.182765809508;7835.37317014626;8017.478863873829;5604.989274648492;5622.465865742748;5695.715214239189;5701.828945131652;5884.176215329943;5503.257060296809;5569.860753481195;5582.944170558242;5586.832395028156;5664.26364572603 13:13:08 INFO - PROCESS | 29129 | |4;prototype.html;325.78335406954363;332.94173519634063;334.6068369665643;335.54533606962565;342.8662821499617;5454.427285135063;5504.587155963299;5505.1923519005295;5510.622344413897;5543.77824887005;5163.767630456629;5217.843464696056;5368.677879327243;5383.623146379745;5431.185322201672;4211.136716973028;4230.302000169968;4242.681798865097;4247.851325203618;4252.744835309892;4235.089455767024;4248.830046798124;4253.957460425402;4254.893627659302;4256.4253825733595;4102.774347410891;4214.136102099078;4219.472565929259;4235.301175306071;4238.936415953764;4219.324908014718;4221.459167403907;4223.577642235752;4228.365745138223;4228.5560016198315;3744.120131769032;3748.137928276484;3748.1566647504364;3752.906177345561;3784.508013958168;5082.517160869711;5257.421683614799;5344.35867695879;5352.357717073937;5357.919631205535;4790.688605240648;4792.353032340624;4822.204336284509;4827.468978412372;4836.395450568679;5629.352624448159;5647.689377084259;5658.7170641467765;5678.01904000399;5700.629459085188;3963.3064213762764;4030.460809689906;4046.3728122141115;4056.249593825152;4067.837286508536;5909;5914.940850591482;5927.585069045169;5932.199153114318;5936.673482958436;4063.6342729154517;4063.9433747225935;4073.572274911151;4074.348104303297;4096.1193343431305;4015.417764424142;4098.098418347959;4166.791660417028;4177.12280421115;4181.623653871166;4762.166620841339;4763.428388593391;4778.808847646124;4785.665003449794;4802.303665968485;5706.658935150236;5723.341815691166;5734.276218796039;5801.477866991991;5835.35811060783;1392.7632302508598;1426.8157429333835;1433.1329545624956;1434.0965191929017;1435.9282035898373;905.2712566383992;923.2198792020732;924.8890133183926;937.95310234488;938.4369378372921;4949.90100197994;4972.776225069881;5000.74996250186;5008.624353173526;5058.342415485963;5126.948975460096;5182.792688292426;5190.480951904779;5200.81797137106;5246.344206974129;4879.975600122048;4928.383952005999;4937.23472861707;4973.35346404965;5335.493128152819;3128.9374212515622;3168.287135394564;3169.4770362890326;3170.128214740919;3182.204448887778;6127.724252408652;6142.477889379364;6157.6613286269685;6157.96921015392;6160.568760186744;5832;5893.351731309553;5978.282606087298;5979.671118088547;5996.910046349309;6042.395760423922;6046.911555919889;6070.3019152797115;6150.753969841246;6157.96921015392;5655.858603534944;5896.823095306976;5926.970365148147;5929.436703513159;5949.613275137102;5842.2405087338375;5884.676342801187;5892.642942822905;5892.705364731661;5908.675022873783;6118.143459915526;6164.907526387018;6192;6192.8761424770355;6249.812505624838;3958.6437220650278;4121.9381709274985;4142.502899652061;4194.601512856274;4196.139791342706;5707.9429205709075;5763.250777398911;5868.5305175585;5953.707822488286;5958.851028724143;5798.1302804579655;5800.419958004166;5803.216565763737;5835.387284335084;5856.736446859816;5558.305211848519;5723.170140329712;5726.226959360433;5728;5780.190773291826;5717.485426311652;5723.7424315905055;5730.025895597675;5735.56983226268;5765.4811067004175;5750.654960702204;5756.539476841759;5759.366469688414;5787.3378246007305;5794.7102644866745;5449.482299181572;5595.776168953196;5627.5497960162265;5634.605577609528;5655.208270842168 13:13:08 INFO - PROCESS | 29129 | |5;yui.html;781.5271760584847;797.4457751862456;812.3338862133049;824.2499325613693;921.7234829551134;16544.420945266917;16845.32618695251;16854.747178792328;16878.409255676055;16968.406105786285;16293.429729959455;16477.340906363763;16522.25649845757;16542;16581.751273730904;732.9596872172028;749.3293502315424;756.9280918312752;884.7920738626418;889.4618755652817;861.8362511122898;877.5612193903048;878.7232021913077;881.1584936385768;881.3610132653839;939.6288466055904;941.5245301122924;941.7363138321247;942.3356533643799;942.3686130292687;931.9347645664806;932.5570354081825;934.1032608695626;934.4766930518897;935.0322416299132;690.2303931116769;690.2441826200303;691.029104108728;691.180950573572;691.4122995453874;15954.361825526965;16235.918820405823;16274.104924229163;16299.266533006043;16318.265678044398;16428.342866285333;16491.422800202054;16875.831241687672;17221;17441.127943602896;13297.3351332433;13430.529931452353;13482.730345393038;13606.659833504142;13860.376283067286;4128.504579450455;4140.855070072563;4176.812043458051;4231.048014196812;4238.19474299882;86847.56576217142;87234.56382718109;88410;89790;92928.07071929194;12217.816732749016;12436;12463.355472993475;12659.493620255087;12845.743085138245;24844.627330590054;25115.37211569689;25372.365690857507;25540;25727.742722572908;9285.350025498285;9384.67153649619;9439.292053096045;9537;9641.37331073478;4939.1109600271575;5086.745662716849;5108.872278193075;5123.641345105882;5286.603504737083;3973.9403908940812;4043.2924238258774;4094.1197642507004;4109.712320137562;4109.91780164395;655.7377049180366;660.9471242300605;682.0689758479739;692.0380670867498;702.2521015118882;619.863630001399;698.7414656576998;717.8994940708305;746.6005686957452;824.2005254902734;10050.872201655262;10301.278910476194;10694.572217111385;10790.838137427945;11369.431528423545;708.2457183099982;709.1738125084322;834.2616784146074;855.5508358112041;858.7423772868115;4932.482089380635;5169.353830771153;5172.637915345965;5222.086134926372;5367.490088441592;107436;118367.40816296036;121996;122706.02410843267;125724.37137814252;104485.47757261318;106482;108181.45909270403;108952.4552377233;109676.45161774299;61455;80325.59837200776;91693.54153229191;92350.07649923414;92875.53562232146;10291.639792607222;10563.15494760402;10599.198136316998;10725.785484290114;10826.56693732242;52788.736056319474;53557.73221133869;55029;55378.72310638421;56256;77364;82156;83021;85258.57370713107;85331;11729.589464368706;11844.585439509576;12394.380280985733;12704.555340563036;12934.870651293742;14265.786013209603;14713.33789979475;14881;14901.627459313604;15029.173395463355;14235.572932812032;14372.353244103831;14484.565463036126;15095.924520377328;15112.168830714416;13982.790258146339;14295.356708947915;14397.42410303576;15289.847101528841;15308.158051307286;12154.1492095556;12252.75494490123;12354.938225308815;12810.103292769416;13059.608211753663;10580.947095264475;11184.440777961232;11761.176717629683;11855.644330670093;12216.938915305722;11918.702032449259;12023.519059237533;12057.336846473529;12179.468103046398;12201.572944946884 13:13:08 INFO - PROCESS | 29129 | __end_tp_report 13:13:08 INFO - PROCESS | 29129 | __start_cc_report 13:13:08 INFO - PROCESS | 29129 | _x_x_mozilla_cycle_collect,1136 13:13:08 INFO - PROCESS | 29129 | __end_cc_report 13:13:08 INFO - PROCESS | 29129 | __startTimestamp1474575188940__endTimestamp 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | ------- Summary: start ------- 13:13:08 INFO - PROCESS | 29129 | Number of tests: 6 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#0] dojo.html Cycles:165 Average:5654.81 Median:5893.71 stddev:647.24 (11.0%) stddev-sans-first:620.64 13:13:08 INFO - PROCESS | 29129 | Values: 3230.2 3238.9 3267.4 3288.6 3301.2 5611.4 5696.2 5706.5 5706.9 5712.7 5672.1 5717.1 5732.6 5737.4 5748.4 4973.4 5074.1 5177.2 5179.1 5219.0 5170.5 5213.9 5215.5 5218.3 5219.5 5096.1 5138.9 5177.9 5190.4 5201.8 5241.4 5245.0 5248.3 5252.1 5282.9 5012.8 5070.0 5126.1 5179.0 5187.5 5563.4 5563.7 5565.6 5577.3 5582.6 6078.3 6083.5 6088.6 6091.7 6097.0 5854.9 6005.5 6037.5 6056.6 6145.5 5678.5 5682.1 5701.0 5705.4 5727.2 6217.0 6221.8 6242.9 6248.5 6260.7 5785.1 5902.1 5960.1 5965.1 6051.5 6120.1 6123.1 6124.2 6125.7 6131.3 6067.9 6077.1 6078.5 6079.6 6108.2 5777.1 5931.5 5950.6 5988.3 6086.9 4620.3 4651.3 4655.3 4655.8 4689.0 4148.1 4157.6 4160.2 4178.4 4186.8 5578.0 5701.3 5736.7 5791.4 5871.5 6199.9 6201.2 6202.0 6207.0 6212.4 5857.6 5860.8 5863.1 5871.6 5902.5 5157.1 5211.2 5224.0 5248.4 5339.3 6331.8 6333.8 6341.2 6352.0 6354.7 6055.5 6073.5 6089.6 6118.0 6122.0 6107.0 6136.6 6240.7 6300.5 6330.2 6050.0 6056.4 6066.9 6067.2 6080.2 6144.0 6155.0 6166.3 6172.4 6176.9 5732.8 5845.6 5924.6 5939.4 6008.2 5907.1 5948.1 5951.3 5987.5 6009.0 5847.6 5913.7 5964.1 5971.6 5980.7 5781.6 5924.9 5947.4 5984.0 5996.9 5889.8 5893.7 5901.8 5914.5 5926.4 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#1] ext.html Cycles:180 Average:66116.07 Median:43529.89 stddev:130880.68 (300.7%) stddev-sans-first:131155.34 13:13:08 INFO - PROCESS | 29129 | Values: 583.0 638.8 655.8 659.6 672.3 33979.8 37366.6 37432.8 37555.0 37559.0 48031.5 49002.5 49012.0 52554.0 52609.2 9243.7 9409.3 9426.5 9431.3 9434.7 8661.4 8864.1 9305.8 9391.1 9391.2 8998.1 9044.0 11237.3 11487.4 11494.5 11209.0 11254.0 11277.0 11281.5 11319.7 11258.2 11322.4 11330.8 11339.8 11343.4 46133.0 46515.1 46575.3 46675.3 46786.5 3977.9 4087.9 4113.1 4151.5 4359.6 34381.3 34453.1 34928.0 35223.0 36833.0 4691.6 4747.3 4786.9 4884.2 4943.0 359910.0 359931.0 360209.0 360316.0 361413.0 64514.4 65210.7 65315.7 65392.0 65536.0 40281.6 40572.6 40808.2 43479.8 43591.6 20211.7 20325.0 20389.4 20406.9 20442.3 15703.7 15769.7 15812.9 15820.7 15831.8 18842.2 20693.7 21196.5 21344.0 21444.5 7489.5 7496.6 7701.2 7728.6 7912.9 960.9 1046.3 1063.5 1151.9 1154.6 34767.7 35550.6 35586.6 35641.6 35763.8 1120.4 1179.0 1181.8 1187.9 1220.6 18321.6 18351.4 18423.7 18455.4 18510.3 566099.0 569780.0 571701.0 571822.0 572327.0 64730.7 65137.3 65147.3 65304.7 65344.0 1347.6 1411.3 1453.8 1455.4 1496.5 33276.7 35934.3 36308.8 36970.4 36992.3 55182.7 59829.0 61536.7 61572.4 61615.4 518056.0 523611.0 528342.0 528789.0 528914.0 40164.2 45489.8 45744.8 45876.0 50478.5 52085.0 52161.0 52194.0 52378.5 52476.2 42952.8 44360.8 44669.6 45550.0 47033.5 45335.5 45907.0 46059.0 46186.5 46705.0 43580.0 44261.3 44483.8 44661.0 44727.8 37012.4 40197.6 40606.0 40819.0 44539.3 29823.0 29996.4 30430.4 30599.7 30859.5 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#2] jquery.html Cycles:180 Average:31157.60 Median:12056.53 stddev:89223.60 (740.0%) stddev-sans-first:89444.05 13:13:08 INFO - PROCESS | 29129 | Values: 423.2 434.3 434.5 439.8 443.7 19175.4 20713.0 20982.3 21744.4 21887.8 19927.3 20131.9 20631.5 20764.7 20785.0 5209.8 5479.7 5483.5 5484.9 5487.0 5262.6 5339.0 5368.4 5391.2 5411.7 5380.6 5385.5 5395.8 5395.8 5397.4 5393.4 5596.7 5831.6 5857.1 5960.9 5727.3 5774.7 5795.6 5986.5 6029.6 14241.8 14571.3 14575.3 19459.4 24716.9 2413.6 2452.7 2452.7 2590.3 2848.0 10311.1 10479.6 10481.0 10691.5 14448.2 1997.7 1997.8 2041.7 2067.7 2076.5 87448.0 89954.6 90093.0 91453.0 93056.5 11369.0 11646.2 11767.2 11811.9 11827.5 17252.5 17252.7 17286.6 17288.7 17296.1 9038.6 9159.5 9209.5 9211.6 9344.8 9038.6 9065.5 9079.5 9278.7 9296.3 4871.9 5005.1 5048.8 5282.5 5287.6 2102.5 2112.5 2127.6 2158.2 2225.3 953.9 972.4 1026.9 1048.9 1073.3 18222.0 21061.3 21150.8 21202.4 21297.8 1020.4 1044.2 1080.4 1108.4 1153.3 10676.8 11619.1 11674.6 11737.5 11748.9 532770.0 535089.0 538068.0 542443.0 558194.0 94470.1 95719.5 96606.5 96769.0 98921.0 1005.8 1045.1 1073.8 1091.6 1092.9 17562.8 17806.9 18083.5 18317.3 18581.6 34813.3 41214.0 43494.6 43754.0 44308.6 55884.0 60312.4 61415.0 61884.7 63079.1 7920.6 8312.6 8960.8 9166.3 9386.6 13872.8 14207.3 14236.2 14377.2 14514.4 12422.0 12440.8 12480.9 12549.0 12598.5 12041.5 12258.9 12260.9 12344.6 12804.1 12075.3 12305.2 12500.0 12733.2 12739.9 8283.5 8430.2 8599.0 8753.3 8822.2 11092.9 11484.8 11850.5 12028.3 12360.9 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#3] mootools.html Cycles:180 Average:6342.49 Median:2601.86 stddev:3726.79 (143.2%) stddev-sans-first:3718.71 13:13:08 INFO - PROCESS | 29129 | Values: 1396.8 1545.8 1575.8 1742.5 1853.5 9182.2 9622.1 9640.6 9973.8 9982.0 9773.4 9894.7 9900.7 9909.6 9911.7 2462.4 2501.4 2510.7 2512.1 2512.4 2434.7 2436.6 2443.3 2443.9 2445.5 2370.0 2423.3 2424.3 2431.5 2432.4 2404.2 2424.1 2428.1 2432.3 2438.0 2353.8 2402.8 2407.3 2412.2 2412.5 9395.1 9572.7 9703.9 9723.5 9770.2 5479.2 5742.9 5744.6 5753.4 5759.7 3573.4 3640.2 3667.3 3670.4 3673.7 1170.7 1191.8 1193.7 1231.5 1247.0 13834.1 13935.9 13959.4 13966.8 13990.8 4480.7 4688.1 4750.4 4813.0 4832.5 10795.6 10795.8 10797.9 10804.8 10804.8 5563.3 5584.1 5589.9 5606.3 5612.5 4547.6 4664.3 4768.4 5180.1 5226.7 4060.1 4137.2 4160.3 4162.8 4261.9 1214.4 1216.0 1224.3 1232.2 1233.9 945.5 953.3 1018.3 1080.5 1106.3 6457.2 6495.5 6612.7 6711.7 6824.5 471.1 477.4 483.3 499.2 499.2 4456.8 4602.1 4605.8 4732.6 4742.9 11364.4 11791.6 12230.5 12302.4 12315.0 11639.8 12123.2 12147.4 12180.1 12192.9 8934.9 9114.8 9121.1 9125.8 9126.4 6614.4 6640.8 6651.6 6787.6 6825.8 10755.9 11315.7 11325.7 11327.9 11420.6 11333.7 11374.7 11458.4 11459.8 11512.8 8669.4 8802.5 8860.9 8863.6 8900.4 9233.5 9440.2 9454.7 9465.0 9465.4 8409.5 8481.6 8568.0 8683.3 8787.3 8305.6 8441.7 8448.1 8450.2 8657.7 7100.6 7607.4 7783.2 7835.4 8017.5 5605.0 5622.5 5695.7 5701.8 5884.2 5503.3 5569.9 5582.9 5586.8 5664.3 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#4] prototype.html Cycles:180 Average:4769.34 Median:5355.14 stddev:1419.19 (26.5%) stddev-sans-first:1383.43 13:13:08 INFO - PROCESS | 29129 | Values: 325.8 332.9 334.6 335.5 342.9 5454.4 5504.6 5505.2 5510.6 5543.8 5163.8 5217.8 5368.7 5383.6 5431.2 4211.1 4230.3 4242.7 4247.9 4252.7 4235.1 4248.8 4254.0 4254.9 4256.4 4102.8 4214.1 4219.5 4235.3 4238.9 4219.3 4221.5 4223.6 4228.4 4228.6 3744.1 3748.1 3748.2 3752.9 3784.5 5082.5 5257.4 5344.4 5352.4 5357.9 4790.7 4792.4 4822.2 4827.5 4836.4 5629.4 5647.7 5658.7 5678.0 5700.6 3963.3 4030.5 4046.4 4056.2 4067.8 5909.0 5914.9 5927.6 5932.2 5936.7 4063.6 4063.9 4073.6 4074.3 4096.1 4015.4 4098.1 4166.8 4177.1 4181.6 4762.2 4763.4 4778.8 4785.7 4802.3 5706.7 5723.3 5734.3 5801.5 5835.4 1392.8 1426.8 1433.1 1434.1 1435.9 905.3 923.2 924.9 938.0 938.4 4949.9 4972.8 5000.7 5008.6 5058.3 5126.9 5182.8 5190.5 5200.8 5246.3 4880.0 4928.4 4937.2 4973.4 5335.5 3128.9 3168.3 3169.5 3170.1 3182.2 6127.7 6142.5 6157.7 6158.0 6160.6 5832.0 5893.4 5978.3 5979.7 5996.9 6042.4 6046.9 6070.3 6150.8 6158.0 5655.9 5896.8 5927.0 5929.4 5949.6 5842.2 5884.7 5892.6 5892.7 5908.7 6118.1 6164.9 6192.0 6192.9 6249.8 3958.6 4121.9 4142.5 4194.6 4196.1 5707.9 5763.3 5868.5 5953.7 5958.9 5798.1 5800.4 5803.2 5835.4 5856.7 5558.3 5723.2 5726.2 5728.0 5780.2 5717.5 5723.7 5730.0 5735.6 5765.5 5750.7 5756.5 5759.4 5787.3 5794.7 5449.5 5595.8 5627.5 5634.6 5655.2 13:13:08 INFO - PROCESS | 29129 | 13:13:08 INFO - PROCESS | 29129 | [#5] yui.html Cycles:180 Average:22322.64 Median:4008.62 stddev:31944.18 (796.9%) stddev-sans-first:31992.84 13:13:08 INFO - PROCESS | 29129 | Values: 781.5 797.4 812.3 824.2 921.7 16544.4 16845.3 16854.7 16878.4 16968.4 16293.4 16477.3 16522.3 16542.0 16581.8 733.0 749.3 756.9 884.8 889.5 861.8 877.6 878.7 881.2 881.4 939.6 941.5 941.7 942.3 942.4 931.9 932.6 934.1 934.5 935.0 690.2 690.2 691.0 691.2 691.4 15954.4 16235.9 16274.1 16299.3 16318.3 16428.3 16491.4 16875.8 17221.0 17441.1 13297.3 13430.5 13482.7 13606.7 13860.4 4128.5 4140.9 4176.8 4231.0 4238.2 86847.6 87234.6 88410.0 89790.0 92928.1 12217.8 12436.0 12463.4 12659.5 12845.7 24844.6 25115.4 25372.4 25540.0 25727.7 9285.4 9384.7 9439.3 9537.0 9641.4 4939.1 5086.7 5108.9 5123.6 5286.6 3973.9 4043.3 4094.1 4109.7 4109.9 655.7 660.9 682.1 692.0 702.3 619.9 698.7 717.9 746.6 824.2 10050.9 10301.3 10694.6 10790.8 11369.4 708.2 709.2 834.3 855.6 858.7 4932.5 5169.4 5172.6 5222.1 5367.5 107436.0 118367.4 121996.0 122706.0 125724.4 104485.5 106482.0 108181.5 108952.5 109676.5 61455.0 80325.6 91693.5 92350.1 92875.5 10291.6 10563.2 10599.2 10725.8 10826.6 52788.7 53557.7 55029.0 55378.7 56256.0 77364.0 82156.0 83021.0 85258.6 85331.0 11729.6 11844.6 12394.4 12704.6 12934.9 14265.8 14713.3 14881.0 14901.6 15029.2 14235.6 14372.4 14484.6 15095.9 15112.2 13982.8 14295.4 14397.4 15289.8 15308.2 12154.1 12252.8 12354.9 12810.1 13059.6 10580.9 11184.4 11761.2 11855.6 12216.9 11918.7 12023.5 12057.3 12179.5 12201.6 13:13:08 INFO - PROCESS | 29129 | -------- Summary: end -------- 13:13:08 INFO - PROCESS | 29129 | 13:13:09 INFO - TEST-INFO | 29129: exit 0 13:13:09 INFO - TEST-OK | dromaeo_css | took 1112735ms 13:13:09 INFO - TEST-START | kraken 13:13:09 INFO - operating with platform_type : linux_ 13:13:09 INFO - Initialising browser for kraken test... 13:13:09 INFO - TEST-INFO | started process 29329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5nz3i9/profile http://localhost:40993/getInfo.html) 13:13:13 INFO - PROCESS | 29329 | __metrics Screen width/height:1600/1200 13:13:13 INFO - PROCESS | 29329 | colorDepth:24 13:13:13 INFO - PROCESS | 29329 | Browser inner width/height: 1024/697 13:13:13 INFO - PROCESS | 29329 | __metrics 13:13:14 INFO - PROCESS | 29329 | 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" 13:13:14 INFO - PROCESS | 29329 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:13:15 INFO - TEST-INFO | 29329: exit 0 13:13:15 INFO - Browser initialized. 13:13:15 INFO - Running cycle 1/1 for kraken test... 13:13:15 INFO - TEST-INFO | started process 29414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5nz3i9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:26 INFO - PROCESS | 29414 | RSS: Main: 143736832 13:13:26 INFO - PROCESS | 29414 | 13:13:29 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40993/tests/kraken/driver.html?testName=audio-beat-detection) 13:13:30 INFO - PROCESS | 29414 | RSS: Main: 198807552 13:13:30 INFO - PROCESS | 29414 | 13:13:32 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40993/tests/kraken/driver.html?testName=audio-dft) 13:13:33 INFO - PROCESS | 29414 | RSS: Main: 183640064 13:13:33 INFO - PROCESS | 29414 | 13:13:36 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40993/tests/kraken/driver.html?testName=audio-fft) 13:13:36 INFO - PROCESS | 29414 | RSS: Main: 274903040 13:13:36 INFO - PROCESS | 29414 | 13:13:38 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40993/tests/kraken/driver.html?testName=audio-oscillator) 13:13:39 INFO - PROCESS | 29414 | RSS: Main: 179941376 13:13:39 INFO - PROCESS | 29414 | 13:13:41 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40993/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:13:42 INFO - PROCESS | 29414 | RSS: Main: 178434048 13:13:42 INFO - PROCESS | 29414 | 13:13:45 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40993/tests/kraken/driver.html?testName=imaging-darkroom) 13:13:45 INFO - PROCESS | 29414 | RSS: Main: 214880256 13:13:45 INFO - PROCESS | 29414 | 13:13:49 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40993/tests/kraken/driver.html?testName=imaging-desaturate) 13:13:49 INFO - PROCESS | 29414 | RSS: Main: 214028288 13:13:49 INFO - PROCESS | 29414 | 13:13:52 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40993/tests/kraken/driver.html?testName=json-parse-financial) 13:13:52 INFO - PROCESS | 29414 | RSS: Main: 213475328 13:13:52 INFO - PROCESS | 29414 | 13:13:54 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40993/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:13:55 INFO - PROCESS | 29414 | RSS: Main: 175824896 13:13:55 INFO - PROCESS | 29414 | 13:13:56 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:13:57 INFO - PROCESS | 29414 | RSS: Main: 182476800 13:13:57 INFO - PROCESS | 29414 | 13:13:59 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:14:00 INFO - PROCESS | 29414 | RSS: Main: 184225792 13:14:00 INFO - PROCESS | 29414 | 13:14:02 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:14:03 INFO - PROCESS | 29414 | RSS: Main: 184627200 13:14:03 INFO - PROCESS | 29414 | 13:14:05 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:14:06 INFO - PROCESS | 29414 | RSS: Main: 183037952 13:14:06 INFO - PROCESS | 29414 | 13:14:07 INFO - PROCESS | 29414 | Cycle 1(1): loaded http://localhost:40993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40993/tests/kraken/driver.html?testName=ai-astar) 13:14:08 INFO - PROCESS | 29414 | RSS: Main: 234262528 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | __start_tp_report 13:14:08 INFO - PROCESS | 29414 | _x_x_mozilla_page_load 13:14:08 INFO - PROCESS | 29414 | _x_x_mozilla_page_load_details 13:14:08 INFO - PROCESS | 29414 | |i|pagename|runs| 13:14:08 INFO - PROCESS | 29414 | |0;ai-astar;109;110;119;110;109;111;109;109;110;110 13:14:08 INFO - PROCESS | 29414 | |1;audio-beat-detection;143;115;148;180;116;146;142;120;190;114 13:14:08 INFO - PROCESS | 29414 | |2;audio-dft;162;161;160;159;160;161;160;159;158;165 13:14:08 INFO - PROCESS | 29414 | |3;audio-fft;74;76;75;178;75;75;73;75;171;74 13:14:08 INFO - PROCESS | 29414 | |4;audio-oscillator;83;84;166;84;85;83;141;84;83;142 13:14:08 INFO - PROCESS | 29414 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;116;117;117 13:14:08 INFO - PROCESS | 29414 | |6;imaging-darkroom;145;146;145;146;146;145;145;147;146;146 13:14:08 INFO - PROCESS | 29414 | |7;imaging-desaturate;95;95;94;94;94;94;94;94;94;94 13:14:08 INFO - PROCESS | 29414 | |8;json-parse-financial;51;52;52;50;49;49;49;50;51;49 13:14:08 INFO - PROCESS | 29414 | |9;json-stringify-tinderbox;60;60;61;60;61;60;60;60;60;61 13:14:08 INFO - PROCESS | 29414 | |10;stanford-crypto-aes;72;73;71;81;78;79;72;72;72;72 13:14:08 INFO - PROCESS | 29414 | |11;stanford-crypto-ccm;120;121;126;121;120;125;114;126;147;120 13:14:08 INFO - PROCESS | 29414 | |12;stanford-crypto-pbkdf2;152;151;150;151;149;150;154;152;149;150 13:14:08 INFO - PROCESS | 29414 | |13;stanford-crypto-sha256-iterative;61;60;61;59;60;60;60;60;59;59 13:14:08 INFO - PROCESS | 29414 | __end_tp_report 13:14:08 INFO - PROCESS | 29414 | __start_cc_report 13:14:08 INFO - PROCESS | 29414 | _x_x_mozilla_cycle_collect,1678 13:14:08 INFO - PROCESS | 29414 | __end_cc_report 13:14:08 INFO - PROCESS | 29414 | __startTimestamp1474575248053__endTimestamp 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | ------- Summary: start ------- 13:14:08 INFO - PROCESS | 29414 | Number of tests: 14 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.00 stddev:3.03 (2.8%) stddev-sans-first:3.15 13:14:08 INFO - PROCESS | 29414 | Values: 109.0 110.0 119.0 110.0 109.0 111.0 109.0 109.0 110.0 110.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:144.50 stddev:26.81 (18.6%) stddev-sans-first:28.43 13:14:08 INFO - PROCESS | 29414 | Values: 143.0 115.0 148.0 180.0 116.0 146.0 142.0 120.0 190.0 114.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#2] audio-dft Cycles:10 Average:160.50 Median:160.50 stddev:1.96 (1.2%) stddev-sans-first:2.00 13:14:08 INFO - PROCESS | 29414 | Values: 162.0 161.0 160.0 159.0 160.0 161.0 160.0 159.0 158.0 165.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:42.15 (56.2%) stddev-sans-first:44.04 13:14:08 INFO - PROCESS | 29414 | Values: 74.0 76.0 75.0 178.0 75.0 75.0 73.0 75.0 171.0 74.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#4] audio-oscillator Cycles:10 Average:103.50 Median:83.50 stddev:32.56 (39.0%) stddev-sans-first:33.67 13:14:08 INFO - PROCESS | 29414 | Values: 83.0 84.0 166.0 84.0 85.0 83.0 141.0 84.0 83.0 142.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 13:14:08 INFO - PROCESS | 29414 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 13:14:08 INFO - PROCESS | 29414 | Values: 145.0 146.0 145.0 146.0 146.0 145.0 145.0 147.0 146.0 146.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 13:14:08 INFO - PROCESS | 29414 | Values: 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:50.50 stddev:1.23 (2.4%) stddev-sans-first:1.27 13:14:08 INFO - PROCESS | 29414 | Values: 51.0 52.0 52.0 50.0 49.0 49.0 49.0 50.0 51.0 49.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 13:14:08 INFO - PROCESS | 29414 | Values: 60.0 60.0 61.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:72.50 stddev:3.65 (5.0%) stddev-sans-first:3.78 13:14:08 INFO - PROCESS | 29414 | Values: 72.0 73.0 71.0 81.0 78.0 79.0 72.0 72.0 72.0 72.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:123.00 stddev:8.84 (7.2%) stddev-sans-first:9.26 13:14:08 INFO - PROCESS | 29414 | Values: 120.0 121.0 126.0 121.0 120.0 125.0 114.0 126.0 147.0 120.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.80 Median:151.00 stddev:1.55 (1.0%) stddev-sans-first:1.58 13:14:08 INFO - PROCESS | 29414 | Values: 152.0 151.0 150.0 151.0 149.0 150.0 154.0 152.0 149.0 150.0 13:14:08 INFO - PROCESS | 29414 | 13:14:08 INFO - PROCESS | 29414 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.90 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 13:14:08 INFO - PROCESS | 29414 | Values: 61.0 60.0 61.0 59.0 60.0 60.0 60.0 60.0 59.0 59.0 13:14:08 INFO - PROCESS | 29414 | -------- Summary: end -------- 13:14:08 INFO - PROCESS | 29414 | 13:14:09 INFO - TEST-INFO | 29414: exit 0 13:14:09 INFO - TEST-OK | kraken | took 59391ms 13:14:09 INFO - SUITE-END | took 1172s 13:14:09 INFO - Completed test suite (00:19:32) 13:14:09 INFO - javascript benchmark 13:14:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3230.1924518870283, 3238.919027024324, 3267.411865864145, 3288.588926384202, 3301.1747063234193, 5611.410801865802, 5696.231008813819, 5706.457886500786, 5706.885862282752, 5712.714364281785, 5672.06410942194, 5717.085266357383, 5732.5987180897355, 5737.426257374271, 5748.367679555246, 4973.378327709036, 5074.086664400406, 5177.249298851673, 5179.119549676564, 5219.034478621448, 5170.457102004292, 5213.947860521384, 5215.530602245816, 5218.295530103444, 5219.478052194788, 5096.082705113096, 5138.948610513903, 5177.87055323618, 5190.429052804188, 5201.765920533585, 5241.42344342122, 5244.9737751310995, 5248.291480650101, 5252.107141785906, 5282.894342113174, 5012.8496145115705, 5070.0, 5126.128558145124, 5179.0, 5187.533122018998, 5563.443655634444, 5563.693996830173, 5565.582581306418, 5577.274954255961, 5582.581306401996, 6078.331383547807, 6083.513318934474, 6088.604240724383, 6091.725872335755, 6097.0, 5854.941450585482, 6005.549583781234, 6037.54718396122, 6056.576039677225, 6145.508359331243, 5678.545716342683, 5682.119271512922, 5700.971495142539, 5705.429457054262, 5727.198192253088, 6217.0052791553135, 6221.782237621752, 6242.937570624235, 6248.5313601480075, 6260.749570017239, 5785.103308987113, 5902.055671092605, 5960.0761881908375, 5965.135055417027, 6051.515878729691, 6120.14317995481, 6123.142760013601, 6124.234470691164, 6125.663088530174, 6131.294901086432, 6067.908981365283, 6077.1491991121275, 6078.452939235402, 6079.604825686405, 6108.236470441195, 5777.133429985536, 5931.466168044896, 5950.613210141327, 5988.311344195473, 6086.878262434727, 4620.283856002325, 4651.279051746985, 4655.2784318430695, 4655.790489427984, 4689.038747056845, 4148.087420767426, 4157.605027522381, 4160.18876319121, 4178.35235538492, 4186.832526698959, 5578.0, 5701.287339082614, 5736.655800651891, 5791.391903850119, 5871.4715675589405, 6199.907001394983, 6201.224846894138, 6202.038684003986, 6206.968965155235, 6212.4408803207925, 5857.648541087463, 5860.794872179454, 5863.061910094364, 5871.647701137946, 5902.527797776168, 5157.149070403341, 5211.1662134059125, 5223.973880130575, 5248.396434410015, 5339.252504649352, 6331.841703957346, 6333.841653958688, 6341.207349081365, 6352.047192921099, 6354.650494222862, 6055.485283750754, 6073.544484163617, 6089.604175728564, 6118.021116621319, 6121.969390153021, 6106.969465152646, 6136.570440069152, 6240.687965601793, 6300.464460520724, 6330.177076979963, 6049.969750151221, 6056.36408177135, 6066.878662426815, 6067.180930574316, 6080.209572755513, 6143.96928015357, 6154.953657878082, 6166.321704612399, 6172.444479996642, 6176.938230617637, 5732.770689172553, 5845.649261044351, 5924.555658325557, 5939.4357536034, 6008.218931538872, 5907.143464197581, 5948.13752005948, 5951.345352011189, 5987.461128498456, 6008.969955150196, 5847.649141051551, 5913.674747888907, 5964.135200396005, 5971.641701497924, 5980.671063091571, 5781.566382521243, 5924.851878703067, 5947.434993675594, 5984.0, 5996.910046349395, 5889.793857214977, 5893.705314734332, 5901.7639294427745, 5914.526837853047, 5926.436988486087], "value": 5610.608877710106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.9533637309015, 638.8466767975685, 655.7508146904174, 659.5943494750729, 672.2672287206949, 33979.83010084949, 37366.626333736654, 37432.812835935816, 37555.0, 37559.0, 48031.51968480314, 49002.50997490024, 49012.0, 52554.0, 52609.210861837106, 9243.722688319345, 9409.341346105775, 9426.481543515103, 9431.292653051014, 9434.669786557472, 8661.393702440831, 8864.069272726367, 9305.767355816124, 9391.107844754772, 9391.154796068353, 8998.055204203563, 9043.954780226122, 11237.269577477478, 11487.425628718573, 11494.482748276307, 11209.0, 11254.0, 11277.0, 11281.492332845, 11319.717007074807, 11258.155638327158, 11322.377269250188, 11330.773384532264, 11339.829902551468, 11343.43282835855, 46133.0, 46515.069698606174, 46575.30137047947, 46675.29987050197, 46786.53213467856, 3977.8607748728946, 4087.89780255493, 4113.053997580573, 4151.543330233671, 4359.564043595615, 34381.31237375263, 34453.13867153316, 34928.0, 35223.0, 36833.0, 4691.601213896823, 4747.264174053027, 4786.856394308176, 4884.19410797218, 4942.950570494285, 359910.0, 359931.0, 360209.0, 360316.0, 361413.0, 64514.35485645178, 65210.673946630915, 65315.673421632586, 65392.0, 65536.0, 40281.597184027785, 40572.594274057476, 40808.18383632311, 43479.78260108743, 43591.56408435875, 20211.696824547642, 20325.0, 20389.388318350473, 20406.897965510077, 20442.28452004173, 15703.68592628164, 15769.68460630781, 15812.92093539525, 15820.68358632844, 15831.762523562156, 18842.15210315539, 20693.68959465609, 21196.47008824761, 21344.0, 21444.463888402915, 7489.513181643176, 7496.550206987489, 7701.229877012366, 7728.613569321511, 7912.881306780288, 960.9087136722001, 1046.2937517175876, 1063.5267306048888, 1151.8560179977503, 1154.5958914380067, 34767.65232347644, 35550.64449355525, 35586.64413355833, 35641.64358356383, 35763.82118089393, 1120.4117838135044, 1178.9587364442202, 1181.787278289916, 1187.89902858258, 1220.6399112261904, 18321.63356732858, 18351.35770248035, 18423.72364414535, 18455.354062608014, 18510.259589616533, 566099.0, 569780.0, 571701.0, 571822.0, 572327.0, 64730.67634661891, 65137.348626514075, 65147.348526515074, 65304.67347663327, 65344.0, 1347.5553067487706, 1411.266141606359, 1453.8328092269312, 1455.3887367305756, 1496.498672944554, 33276.66723332736, 35934.281314373045, 36308.81845590755, 36970.44544331783, 36992.26015479622, 55182.72408637931, 59829.0, 61536.69231653813, 61572.38427615846, 61615.38384616096, 518056.0, 523611.0, 528342.0, 528789.0, 528914.0, 40164.1967160661, 45489.772551138354, 45744.77127614341, 45876.0, 50478.49521504738, 52085.0, 52161.0, 52194.0, 52378.47621523736, 52476.212856806414, 42952.78523607362, 44360.77819610881, 44669.55330446654, 45550.0, 47033.52966470292, 45335.54664453313, 45907.0, 46059.0, 46186.50402128725, 46705.0, 43580.0, 44261.33607995818, 44483.777581111885, 44661.0, 44727.77636111798, 37012.44481332728, 40197.59802402055, 40606.0, 40819.0, 44539.33191002073, 29823.0, 29996.400071998003, 30430.391392172474, 30599.694003059685, 30859.537106943862], "value": 22205.58536935165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.1705856521218, 434.25429772315294, 434.51551520055136, 439.7757143856633, 443.71158746814564, 19175.424737257887, 20713.0, 20982.265620703278, 21744.3691723121, 21887.78112218881, 19927.302544410948, 20131.899340503314, 20631.484212894684, 20764.68852967203, 20785.0, 5209.817656382027, 5479.698616576086, 5483.451654834505, 5484.862878428032, 5487.039768040597, 5262.605304602151, 5339.0, 5368.355797304329, 5391.218273350361, 5411.702356370398, 5380.596455265852, 5385.515303622673, 5395.81114660987, 5395.838124856241, 5397.4062853086125, 5393.4336894626085, 5596.664200147984, 5831.6209446386065, 5857.121431785266, 5960.910586341209, 5727.312722473288, 5774.653520788724, 5795.594308398414, 5986.5210783137245, 6029.6382217066675, 14241.78637320441, 14571.344289506891, 14575.271236438135, 19459.4162175135, 24716.876415617808, 2413.613821788523, 2452.705675318973, 2452.742462041496, 2590.3265151060673, 2847.985760071186, 10311.123554497877, 10479.633212837514, 10481.0, 10691.518881650345, 14448.205348705817, 1997.719452867869, 1997.820196182336, 2041.693745938121, 2067.6795096760025, 2076.5223998480283, 87448.0, 89954.55022724844, 90093.0, 91453.0, 93056.53471732666, 11369.0, 11646.184767066394, 11767.176297659082, 11811.940940295244, 11827.467763950643, 17252.482425527003, 17252.741208881627, 17286.567835804206, 17288.740668889975, 17296.135193240287, 9038.63845446188, 9159.49622770754, 9209.539523023823, 9211.63153473867, 9344.813103737888, 9038.63845446188, 9065.546722663841, 9079.45523268606, 9278.72163835086, 9296.30277729173, 4871.926921096187, 5005.124103281911, 5048.823291184791, 5282.498162674539, 5287.57699384056, 2102.4533621258583, 2112.503561663008, 2127.563849410867, 2158.2014654577592, 2225.2879078694987, 953.909378609031, 972.3873959405529, 1026.8511065895402, 1048.8793788714395, 1073.307716522854, 18222.0058809505, 21061.26285580028, 21150.788492114883, 21202.363929082152, 21297.78702212989, 1020.3724709303873, 1044.1698849414734, 1080.443571560647, 1108.4236197177422, 1153.3425947210178, 10676.786464270672, 11619.128565357463, 11674.591389301333, 11737.471813768401, 11748.941255293668, 532770.0, 535089.0, 538068.0, 542443.0, 558194.0, 94470.0552994475, 95719.52140239254, 96606.51696741612, 96769.0, 98921.0, 1005.7938122684832, 1045.137761346892, 1073.8335557988523, 1091.645215305029, 1092.8907109289166, 17562.82437175612, 17806.910965445608, 18083.457496275132, 18317.267309307477, 18581.628367432844, 34813.25933703254, 41213.96965075897, 43494.56505434905, 43754.0, 44308.556914430446, 55884.0, 60312.396876032435, 61415.0, 61884.69057654863, 63079.05381419374, 7920.603969801602, 8312.625931833203, 8960.820783584422, 9166.266698664223, 9386.5776040077, 13872.791908121184, 14207.28963551839, 14236.217008064656, 14377.209253491159, 14514.419423223375, 12422.0, 12440.75118497643, 12480.875191248335, 12549.0, 12598.54328405952, 12041.518339266331, 12258.87741122613, 12260.938695306466, 12344.567940122053, 12804.10371274002, 12075.27548347102, 12305.200161989442, 12500.0, 12733.236005839679, 12739.93630031881, 8283.50298982063, 8430.241278284984, 8598.957005214934, 8753.255973242345, 8822.2060014599, 11092.944535277595, 11484.770304594029, 11850.466728997044, 12028.338441385458, 12360.876391235972], "value": 9641.070311653135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.8044473773666, 1545.814502259729, 1575.763635454682, 1742.468547093136, 1853.4810253129128, 9182.219511341536, 9622.134007939285, 9640.614375424975, 9973.800523989516, 9981.950090249538, 9773.413595184295, 9894.653687120934, 9900.702978910625, 9909.603615855358, 9911.653092141776, 2462.4090218347555, 2501.4246723253664, 2510.698716154055, 2512.1207577347964, 2512.4221429071235, 2434.7443518430578, 2436.5979613363775, 2443.315871555967, 2443.9144629937955, 2445.510897820434, 2369.95260094798, 2423.309356833303, 2424.2848359733835, 2431.465077682907, 2432.3797431654903, 2404.1705611563984, 2424.1394305021727, 2428.138011006085, 2432.2946345559767, 2437.9882348825217, 2353.846999944998, 2402.759724027601, 2407.277816655014, 2412.2039726890084, 2412.517496500707, 9395.107464790834, 9572.712818615453, 9703.902960970441, 9723.46520941348, 9770.218382529381, 5479.232907392969, 5742.885142297131, 5744.626599271034, 5753.424657534255, 5759.740811663443, 3573.446115852047, 3640.1627625645983, 3667.3215455140885, 3670.376036073874, 3673.669369756708, 1170.7073231692077, 1191.8093105103142, 1193.665773583398, 1231.513552146906, 1246.993765031178, 13834.100783449145, 13935.930320348434, 13959.371828267753, 13966.790498142536, 13990.790138147835, 4480.686351955332, 4688.132695451284, 4750.358701575312, 4813.013332266877, 4832.540908613676, 10795.622153224756, 10795.784084318428, 10797.892021079846, 10804.78390432187, 10804.837927431096, 5563.332400111932, 5584.078627026495, 5589.888202235932, 5606.299212598426, 5612.5229355504425, 4547.613452856544, 4664.277037059262, 4768.380110585602, 5180.119379705459, 5226.738663066832, 4060.0864805419137, 4137.234611596864, 4160.271952408317, 4162.812673429703, 4261.893452663709, 1214.4110106598293, 1216.0332535634193, 1224.2960297828783, 1232.2298563397876, 1233.85868072033, 945.4516380499294, 953.270747877874, 1018.2770233134557, 1080.5353697909818, 1106.31961343773, 6457.192850893638, 6495.4803615710625, 6612.669366531654, 6711.6979735912, 6824.488163387766, 471.11900745605254, 477.35556998053045, 483.3209340876075, 499.22121490474973, 499.22370713540795, 4456.799444024962, 4602.125596136723, 4605.792739326738, 4732.5977291929885, 4742.857714268577, 11364.374959377148, 11791.587294444653, 12230.510779568896, 12302.446389912475, 12315.0, 11639.825402618968, 12123.151379403533, 12147.392630368446, 12180.086493513021, 12192.939035304767, 8934.927808663002, 9114.817703646022, 9121.133492318351, 9125.817483650422, 9126.406783559047, 6614.371634694697, 6640.83397915056, 6651.600903945779, 6787.592744435349, 6825.829354266183, 10755.946220268848, 11315.71710707206, 11325.660230193107, 11327.943360283476, 11420.600278990196, 11333.716657083638, 11374.658760237206, 11458.369789661649, 11459.770804583695, 11512.769744605228, 8669.393142479967, 8802.471851688919, 8860.911390886009, 8863.601137948679, 8900.42147260426, 9233.538323083952, 9440.197583205505, 9454.669086582211, 9464.952675236855, 9465.384749991228, 8409.453385529921, 8481.575921203792, 8568.0, 8683.348227584102, 8787.340949428946, 8305.584720763816, 8441.746747597583, 8448.112948140602, 8450.154984501502, 8657.740267791976, 7100.609466479394, 7607.353374962969, 7783.182765809508, 7835.37317014626, 8017.478863873829, 5604.989274648492, 5622.465865742748, 5695.715214239189, 5701.828945131652, 5884.176215329943, 5503.257060296809, 5569.860753481195, 5582.944170558242, 5586.832395028156, 5664.26364572603], "value": 4918.045423613458, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.78335406954363, 332.94173519634063, 334.6068369665643, 335.54533606962565, 342.8662821499617, 5454.427285135063, 5504.587155963299, 5505.1923519005295, 5510.622344413897, 5543.77824887005, 5163.767630456629, 5217.843464696056, 5368.677879327243, 5383.623146379745, 5431.185322201672, 4211.136716973028, 4230.302000169968, 4242.681798865097, 4247.851325203618, 4252.744835309892, 4235.089455767024, 4248.830046798124, 4253.957460425402, 4254.893627659302, 4256.4253825733595, 4102.774347410891, 4214.136102099078, 4219.472565929259, 4235.301175306071, 4238.936415953764, 4219.324908014718, 4221.459167403907, 4223.577642235752, 4228.365745138223, 4228.5560016198315, 3744.120131769032, 3748.137928276484, 3748.1566647504364, 3752.906177345561, 3784.508013958168, 5082.517160869711, 5257.421683614799, 5344.35867695879, 5352.357717073937, 5357.919631205535, 4790.688605240648, 4792.353032340624, 4822.204336284509, 4827.468978412372, 4836.395450568679, 5629.352624448159, 5647.689377084259, 5658.7170641467765, 5678.01904000399, 5700.629459085188, 3963.3064213762764, 4030.460809689906, 4046.3728122141115, 4056.249593825152, 4067.837286508536, 5909.0, 5914.940850591482, 5927.585069045169, 5932.199153114318, 5936.673482958436, 4063.6342729154517, 4063.9433747225935, 4073.572274911151, 4074.348104303297, 4096.1193343431305, 4015.417764424142, 4098.098418347959, 4166.791660417028, 4177.12280421115, 4181.623653871166, 4762.166620841339, 4763.428388593391, 4778.808847646124, 4785.665003449794, 4802.303665968485, 5706.658935150236, 5723.341815691166, 5734.276218796039, 5801.477866991991, 5835.35811060783, 1392.7632302508598, 1426.8157429333835, 1433.1329545624956, 1434.0965191929017, 1435.9282035898373, 905.2712566383992, 923.2198792020732, 924.8890133183926, 937.95310234488, 938.4369378372921, 4949.90100197994, 4972.776225069881, 5000.74996250186, 5008.624353173526, 5058.342415485963, 5126.948975460096, 5182.792688292426, 5190.480951904779, 5200.81797137106, 5246.344206974129, 4879.975600122048, 4928.383952005999, 4937.23472861707, 4973.35346404965, 5335.493128152819, 3128.9374212515622, 3168.287135394564, 3169.4770362890326, 3170.128214740919, 3182.204448887778, 6127.724252408652, 6142.477889379364, 6157.6613286269685, 6157.96921015392, 6160.568760186744, 5832.0, 5893.351731309553, 5978.282606087298, 5979.671118088547, 5996.910046349309, 6042.395760423922, 6046.911555919889, 6070.3019152797115, 6150.753969841246, 6157.96921015392, 5655.858603534944, 5896.823095306976, 5926.970365148147, 5929.436703513159, 5949.613275137102, 5842.2405087338375, 5884.676342801187, 5892.642942822905, 5892.705364731661, 5908.675022873783, 6118.143459915526, 6164.907526387018, 6192.0, 6192.8761424770355, 6249.812505624838, 3958.6437220650278, 4121.9381709274985, 4142.502899652061, 4194.601512856274, 4196.139791342706, 5707.9429205709075, 5763.250777398911, 5868.5305175585, 5953.707822488286, 5958.851028724143, 5798.1302804579655, 5800.419958004166, 5803.216565763737, 5835.387284335084, 5856.736446859816, 5558.305211848519, 5723.170140329712, 5726.226959360433, 5728.0, 5780.190773291826, 5717.485426311652, 5723.7424315905055, 5730.025895597675, 5735.56983226268, 5765.4811067004175, 5750.654960702204, 5756.539476841759, 5759.366469688414, 5787.3378246007305, 5794.7102644866745, 5449.482299181572, 5595.776168953196, 5627.5497960162265, 5634.605577609528, 5655.208270842168], "value": 4313.657901535481, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.5271760584847, 797.4457751862456, 812.3338862133049, 824.2499325613693, 921.7234829551134, 16544.420945266917, 16845.32618695251, 16854.747178792328, 16878.409255676055, 16968.406105786285, 16293.429729959455, 16477.340906363763, 16522.25649845757, 16542.0, 16581.751273730904, 732.9596872172028, 749.3293502315424, 756.9280918312752, 884.7920738626418, 889.4618755652817, 861.8362511122898, 877.5612193903048, 878.7232021913077, 881.1584936385768, 881.3610132653839, 939.6288466055904, 941.5245301122924, 941.7363138321247, 942.3356533643799, 942.3686130292687, 931.9347645664806, 932.5570354081825, 934.1032608695626, 934.4766930518897, 935.0322416299132, 690.2303931116769, 690.2441826200303, 691.029104108728, 691.180950573572, 691.4122995453874, 15954.361825526965, 16235.918820405823, 16274.104924229163, 16299.266533006043, 16318.265678044398, 16428.342866285333, 16491.422800202054, 16875.831241687672, 17221.0, 17441.127943602896, 13297.3351332433, 13430.529931452353, 13482.730345393038, 13606.659833504142, 13860.376283067286, 4128.504579450455, 4140.855070072563, 4176.812043458051, 4231.048014196812, 4238.19474299882, 86847.56576217142, 87234.56382718109, 88410.0, 89790.0, 92928.07071929194, 12217.816732749016, 12436.0, 12463.355472993475, 12659.493620255087, 12845.743085138245, 24844.627330590054, 25115.37211569689, 25372.365690857507, 25540.0, 25727.742722572908, 9285.350025498285, 9384.67153649619, 9439.292053096045, 9537.0, 9641.37331073478, 4939.1109600271575, 5086.745662716849, 5108.872278193075, 5123.641345105882, 5286.603504737083, 3973.9403908940812, 4043.2924238258774, 4094.1197642507004, 4109.712320137562, 4109.91780164395, 655.7377049180366, 660.9471242300605, 682.0689758479739, 692.0380670867498, 702.2521015118882, 619.863630001399, 698.7414656576998, 717.8994940708305, 746.6005686957452, 824.2005254902734, 10050.872201655262, 10301.278910476194, 10694.572217111385, 10790.838137427945, 11369.431528423545, 708.2457183099982, 709.1738125084322, 834.2616784146074, 855.5508358112041, 858.7423772868115, 4932.482089380635, 5169.353830771153, 5172.637915345965, 5222.086134926372, 5367.490088441592, 107436.0, 118367.40816296036, 121996.0, 122706.02410843267, 125724.37137814252, 104485.47757261318, 106482.0, 108181.45909270403, 108952.4552377233, 109676.45161774299, 61455.0, 80325.59837200776, 91693.54153229191, 92350.07649923414, 92875.53562232146, 10291.639792607222, 10563.15494760402, 10599.198136316998, 10725.785484290114, 10826.56693732242, 52788.736056319474, 53557.73221133869, 55029.0, 55378.72310638421, 56256.0, 77364.0, 82156.0, 83021.0, 85258.57370713107, 85331.0, 11729.589464368706, 11844.585439509576, 12394.380280985733, 12704.555340563036, 12934.870651293742, 14265.786013209603, 14713.33789979475, 14881.0, 14901.627459313604, 15029.173395463355, 14235.572932812032, 14372.353244103831, 14484.565463036126, 15095.924520377328, 15112.168830714416, 13982.790258146339, 14295.356708947915, 14397.42410303576, 15289.847101528841, 15308.158051307286, 12154.1492095556, 12252.75494490123, 12354.938225308815, 12810.103292769416, 13059.608211753663, 10580.947095264475, 11184.440777961232, 11761.176717629683, 11855.644330670093, 12216.938915305722, 11918.702032449259, 12023.519059237533, 12057.336846473529, 12179.468103046398, 12201.572944946884], "value": 8150.876661234141, "unit": "score"}], "name": "dromaeo_css", "value": 7695.690894813604, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 119.0, 110.0, 109.0, 111.0, 109.0, 109.0, 110.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 148.0, 180.0, 116.0, 146.0, 142.0, 120.0, 190.0, 114.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 159.0, 160.0, 161.0, 160.0, 159.0, 158.0, 165.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 178.0, 75.0, 75.0, 73.0, 75.0, 171.0, 74.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 166.0, 84.0, 85.0, 83.0, 141.0, 84.0, 83.0, 142.0], "value": 103.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 50.0, 49.0, 49.0, 49.0, 50.0, 51.0, 49.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 71.0, 81.0, 78.0, 79.0, 72.0, 72.0, 72.0, 72.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 121.0, 126.0, 121.0, 120.0, 125.0, 114.0, 126.0, 147.0, 120.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 150.0, 151.0, 149.0, 150.0, 154.0, 152.0, 149.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 59.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0], "value": 59.9, "unit": "score"}], "name": "kraken", "value": 1486.6000000000001, "alertThreshold": 2.0}]} 13:14:09 INFO - Return code: 0 13:14:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:14:09 INFO - # TBPL SUCCESS # 13:14:09 INFO - Running post-action listener: _resource_record_post_action 13:14:09 INFO - [mozharness: 2016-09-22 20:14:09.355401Z] Finished run-tests step (success) 13:14:09 INFO - Running post-run listener: _resource_record_post_run 13:14:09 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 279113728; Read time: 12; Write time: 179536 13:14:09 INFO - TinderboxPrint: CPU usage
12.9% 13:14:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:14:09 INFO - TinderboxPrint: I/O write bytes / time
279,113,728 / 179,536 13:14:09 INFO - TinderboxPrint: CPU idle
8,245.4 (87.1%) 13:14:09 INFO - TinderboxPrint: CPU user
1,178.9 (12.5%) 13:14:09 INFO - TinderboxPrint: Swap in / out
0 / 0 13:14:09 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71274496; Read time: 0; Write time: 35080 13:14:09 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 207839232; Read time: 0; Write time: 144456 13:14:09 INFO - Running post-run listener: _upload_blobber_files 13:14:09 INFO - Blob upload gear active. 13:14:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:14:09 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 13:14:09 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'] 13:14:09 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 13:14:10 INFO - (blobuploader) - INFO - Open directory for files ... 13:14:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:14:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:14:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:15 INFO - (blobuploader) - INFO - Done attempting. 13:14:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:14:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:14:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:16 INFO - (blobuploader) - INFO - Done attempting. 13:14:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:14:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:14:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:17 INFO - (blobuploader) - INFO - Done attempting. 13:14:17 INFO - (blobuploader) - INFO - Iteration through files over. 13:14:17 INFO - Return code: 0 13:14:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:14:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:14:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bbbea6d138ef1e9cde2006b92bd44f81087aa222b7eabc071e5b87baf46c4d1cdae1e04683eb4dfb3181fd137421216736eec9e231480b8dce670c8c3562d33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95623bb521d15027d8e994e625fd1b38d7c1075052cf9f9471185650a49dde64f0cd3c8d3645058fbb469ffc3b50f9f0d80addecb64b7bf8baf4d2ddf2dd1f29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f41cddf22a0fa5a346d48ea321b6a7a6968610c2cdf80a675156c374542197fef1aabfd1d7d78ecf4fc8b2afeac32ac5a17eb633dc43b4b60aad7e727b0b090"} 13:14:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:14:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:14:17 INFO - Contents: 13:14:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0bbbea6d138ef1e9cde2006b92bd44f81087aa222b7eabc071e5b87baf46c4d1cdae1e04683eb4dfb3181fd137421216736eec9e231480b8dce670c8c3562d33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95623bb521d15027d8e994e625fd1b38d7c1075052cf9f9471185650a49dde64f0cd3c8d3645058fbb469ffc3b50f9f0d80addecb64b7bf8baf4d2ddf2dd1f29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f41cddf22a0fa5a346d48ea321b6a7a6968610c2cdf80a675156c374542197fef1aabfd1d7d78ecf4fc8b2afeac32ac5a17eb633dc43b4b60aad7e727b0b090"} 13:14:17 INFO - Running post-run listener: copy_logs_to_upload_dir 13:14:17 INFO - Copying logs to upload dir... 13:14:17 INFO - mkdir: /builds/slave/test/build/upload/logs 13:14:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.387477 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-22 13:14:17.003159) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 13:14:17.007275) ========= 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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 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/0bbbea6d138ef1e9cde2006b92bd44f81087aa222b7eabc071e5b87baf46c4d1cdae1e04683eb4dfb3181fd137421216736eec9e231480b8dce670c8c3562d33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95623bb521d15027d8e994e625fd1b38d7c1075052cf9f9471185650a49dde64f0cd3c8d3645058fbb469ffc3b50f9f0d80addecb64b7bf8baf4d2ddf2dd1f29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f41cddf22a0fa5a346d48ea321b6a7a6968610c2cdf80a675156c374542197fef1aabfd1d7d78ecf4fc8b2afeac32ac5a17eb633dc43b4b60aad7e727b0b090"} build_url:https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011280 build_url: 'https://queue.taskcluster.net/v1/task/H1dHj-AfQ1O8uQ-8DPcMVQ/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/0bbbea6d138ef1e9cde2006b92bd44f81087aa222b7eabc071e5b87baf46c4d1cdae1e04683eb4dfb3181fd137421216736eec9e231480b8dce670c8c3562d33", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95623bb521d15027d8e994e625fd1b38d7c1075052cf9f9471185650a49dde64f0cd3c8d3645058fbb469ffc3b50f9f0d80addecb64b7bf8baf4d2ddf2dd1f29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1f41cddf22a0fa5a346d48ea321b6a7a6968610c2cdf80a675156c374542197fef1aabfd1d7d78ecf4fc8b2afeac32ac5a17eb633dc43b4b60aad7e727b0b090"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 13:14:17.033584) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 13:14:17.033991) ========= 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=1f5564757af27664ca02d71f000001b0-1474573579.725027-666912015 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 13:14:17.118779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 13:14:17.119440) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-22 13:14:17.120099) ========= ========= Total master_lag: 0.18 =========