builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-006 starttime: 1477435965.5 results: success (0) buildid: 20161025131024 builduid: dcc442041cff4bcea2f2bd01e46ce008 revision: c45f9369c95cb492a2dee21e9c5cefde192f573c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.503441) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.504128) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.507673) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.524515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.524889) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-25 15:52:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-10-25 15:52:46 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125852 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.668714) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.669381) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013566 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.721360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:45.721949) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c45f9369c95cb492a2dee21e9c5cefde192f573c --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 c45f9369c95cb492a2dee21e9c5cefde192f573c --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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-25 15:52:46,368 truncating revision to first 12 chars 2016-10-25 15:52:46,368 Setting DEBUG logging. 2016-10-25 15:52:46,368 attempt 1/10 2016-10-25 15:52:46,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c45f9369c95c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-25 15:52:46,729 unpacking tar archive at: mozilla-beta-c45f9369c95c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.739779 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:46.477148) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:46.477625) ========= script_repo_revision: c45f9369c95cb492a2dee21e9c5cefde192f573c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:46.478492) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:46.478941) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-25 15:52:46.490452) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-25 15:52:46.491072) ========= /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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:52:47 INFO - MultiFileLogger online at 20161025 15:52:47 in /builds/slave/test 15:52:47 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 15:52:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:52:47 INFO - {'append_to_log': False, 15:52:47 INFO - 'base_work_dir': '/builds/slave/test', 15:52:47 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:52:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:52:47 INFO - 'branch': 'Mozilla-Beta', 15:52:47 INFO - 'buildbot_json_path': 'buildprops.json', 15:52:47 INFO - 'config_files': ('talos/linux_config.py',), 15:52:47 INFO - 'default_actions': ('clobber', 15:52:47 INFO - 'read-buildbot-config', 15:52:47 INFO - 'download-and-extract', 15:52:47 INFO - 'populate-webroot', 15:52:47 INFO - 'create-virtualenv', 15:52:47 INFO - 'install', 15:52:47 INFO - 'run-tests'), 15:52:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:52:47 INFO - 'download_minidump_stackwalk': True, 15:52:47 INFO - 'download_symbols': 'ondemand', 15:52:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:52:47 INFO - 'tooltool.py': '/tools/tooltool.py', 15:52:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:52:47 INFO - '/tools/misc-python/virtualenv.py')}, 15:52:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:52:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:52:47 INFO - 'installer_path': 'installer.exe', 15:52:47 INFO - 'log_level': 'info', 15:52:47 INFO - 'log_name': 'talos', 15:52:47 INFO - 'log_to_console': True, 15:52:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:52:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:52:47 INFO - 'opt_config_files': (), 15:52:47 INFO - 'pip_index': False, 15:52:47 INFO - 'sps_profile': False, 15:52:47 INFO - 'sps_profile_interval': 0, 15:52:47 INFO - 'suite': 'dromaeojs', 15:52:47 INFO - 'system_bits': '32', 15:52:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:52:47 INFO - 'title': 'talos-linux64-ix-006', 15:52:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:52:47 INFO - 'use_talos_json': True, 15:52:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:52:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:52:47 INFO - 'work_dir': 'build'} 15:52:47 INFO - [mozharness: 2016-10-25 22:52:47.228968Z] Running clobber step. 15:52:47 INFO - Running pre-action listener: _resource_record_pre_action 15:52:47 INFO - Running main action method: clobber 15:52:47 INFO - rmtree: /builds/slave/test/build 15:52:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:52:47 INFO - Running post-action listener: _resource_record_post_action 15:52:47 INFO - [mozharness: 2016-10-25 22:52:47.698299Z] Finished clobber step (success) 15:52:47 INFO - [mozharness: 2016-10-25 22:52:47.698399Z] Running read-buildbot-config step. 15:52:47 INFO - Running pre-action listener: _resource_record_pre_action 15:52:47 INFO - Running main action method: read_buildbot_config 15:52:47 INFO - Using buildbot properties: 15:52:47 INFO - { 15:52:47 INFO - "project": "", 15:52:47 INFO - "product": "firefox", 15:52:47 INFO - "script_repo_revision": "production", 15:52:47 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:52:47 INFO - "repository": "", 15:52:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:52:47 INFO - "buildid": "20161025131024", 15:52:47 INFO - "pgo_build": "True", 15:52:47 INFO - "basedir": "/builds/slave/test", 15:52:47 INFO - "buildnumber": 446, 15:52:47 INFO - "slavename": "talos-linux64-ix-006", 15:52:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:52:47 INFO - "platform": "ubuntu64_hw", 15:52:47 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:52:47 INFO - "branch": "mozilla-beta", 15:52:47 INFO - "repo_path": "releases/mozilla-beta", 15:52:47 INFO - "revision": "c45f9369c95cb492a2dee21e9c5cefde192f573c", 15:52:47 INFO - "stage_platform": "linux64-pgo", 15:52:47 INFO - "builduid": "dcc442041cff4bcea2f2bd01e46ce008", 15:52:47 INFO - "slavebuilddir": "test" 15:52:47 INFO - } 15:52:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:52:47 INFO - Running post-action listener: _resource_record_post_action 15:52:47 INFO - [mozharness: 2016-10-25 22:52:47.700845Z] Finished read-buildbot-config step (success) 15:52:47 INFO - [mozharness: 2016-10-25 22:52:47.700933Z] Running download-and-extract step. 15:52:47 INFO - Running pre-action listener: _resource_record_pre_action 15:52:47 INFO - Running main action method: download_and_extract 15:52:47 INFO - mkdir: /builds/slave/test/build/tests 15:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:52:47 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:52:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:52:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:52:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:52:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:52:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:52:47 INFO - retry: Failed, sleeping 30 seconds before retrying 15:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:53:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:53:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:53:17 INFO - retry: Failed, sleeping 60 seconds before retrying 15:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:54:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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! 15:54:17 INFO - Caught exception: HTTP Error 404: Not Found 15:54:17 INFO - Caught exception: HTTP Error 404: Not Found 15:54:17 INFO - Caught exception: HTTP Error 404: Not Found 15:54:17 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:17 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:18 INFO - Downloaded 1327 bytes. 15:54:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:54:18 INFO - Using the following test package requirements: 15:54:18 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:54:18 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:54:18 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:54:18 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'jsshell-linux-x86_64.zip'], 15:54:18 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:54:18 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:54:18 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:54:18 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:54:18 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:54:18 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:54:18 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:54:18 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15: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')]} 15:54:18 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:54:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:54:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:54:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:54:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:54:18 INFO - retry: Failed, sleeping 30 seconds before retrying 15:54:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:54:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:54:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:54:48 INFO - retry: Failed, sleeping 60 seconds before retrying 15:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:55:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:55:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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! 15:55:48 INFO - Caught exception: HTTP Error 404: Not Found 15:55:48 INFO - Caught exception: HTTP Error 404: Not Found 15:55:48 INFO - Caught exception: HTTP Error 404: Not Found 15:55:48 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:48 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:55:50 INFO - Downloaded 21774599 bytes. 15:55:50 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'] 15:55:50 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 15:55:51 INFO - Return code: 0 15:55:51 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 15:55:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:51 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:55:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:55:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:55:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:55:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:55:51 INFO - retry: Failed, sleeping 30 seconds before retrying 15:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:56:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:56:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:56:21 INFO - retry: Failed, sleeping 60 seconds before retrying 15:57:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:57:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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! 15:57:21 INFO - Caught exception: HTTP Error 404: Not Found 15:57:21 INFO - Caught exception: HTTP Error 404: Not Found 15:57:21 INFO - Caught exception: HTTP Error 404: Not Found 15:57:21 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:57:21 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:22 INFO - Downloaded 21774599 bytes. 15:57:22 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'] 15:57:22 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 15:57:23 INFO - Return code: 0 15:57:23 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')]} 15:57:23 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:57:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:57:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:57:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:57:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:57:23 INFO - retry: Failed, sleeping 30 seconds before retrying 15:57:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:57:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:57:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:57:53 INFO - retry: Failed, sleeping 60 seconds before retrying 15:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:58:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:58:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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! 15:58:53 INFO - Caught exception: HTTP Error 404: Not Found 15:58:53 INFO - Caught exception: HTTP Error 404: Not Found 15:58:53 INFO - Caught exception: HTTP Error 404: Not Found 15:58:53 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:58:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:58:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:58:55 INFO - Downloaded 14413762 bytes. 15:58:55 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'] 15:58:55 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 15:58:55 INFO - Return code: 0 15:58:55 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')]} 15:58:55 INFO - https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:58:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:58:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:58:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:58:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:58:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:58:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:58:55 INFO - retry: Failed, sleeping 30 seconds before retrying 15:59:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 15:59:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:59:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:59:25 INFO - retry: Failed, sleeping 60 seconds before retrying 16:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 16:00:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 16:00:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RqfhivTYScmC1RKsOyMDkQ/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! 16:00:25 INFO - Caught exception: HTTP Error 404: Not Found 16:00:25 INFO - Caught exception: HTTP Error 404: Not Found 16:00:25 INFO - Caught exception: HTTP Error 404: Not Found 16:00:25 INFO - trying https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:25 INFO - Downloading https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 16:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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 16:00:26 INFO - Downloaded 56776167 bytes. 16:00:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:26 INFO - mkdir: /builds/slave/test/properties 16:00:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:00:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:00:26 INFO - Contents: 16:00:26 INFO - build_url:https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 16:00:27 INFO - Running post-action listener: _resource_record_post_action 16:00:27 INFO - Running post-action listener: set_extra_try_arguments 16:00:27 INFO - [mozharness: 2016-10-25 23:00:27.534865Z] Finished download-and-extract step (success) 16:00:27 INFO - [mozharness: 2016-10-25 23:00:27.535098Z] Running populate-webroot step. 16:00:27 INFO - Running pre-action listener: _resource_record_pre_action 16:00:27 INFO - Running main action method: populate_webroot 16:00:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:27 INFO - '--tppagecycles', 16:00:27 INFO - '7'], 16:00:27 INFO - 'tests': ['tsvgm']}}, 16:00:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:00:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:00:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:00:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['damp', 'tps']}, 16:00:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'tests': ['damp', 'tps']}, 16:00:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['dromaeo_dom']}, 16:00:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['basic_compositor_video']}, 16:00:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:00:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'other-e10s': {'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['a11yr', 16:00:27 INFO - 'ts_paint', 16:00:27 INFO - 'tpaint', 16:00:27 INFO - 'sessionrestore', 16:00:27 INFO - 'sessionrestore_no_auto_restore', 16:00:27 INFO - 'tabpaint']}, 16:00:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['tsvgx', 16:00:27 INFO - 'tsvgr_opacity', 16:00:27 INFO - 'tart', 16:00:27 INFO - 'tscrollx', 16:00:27 INFO - 'cart']}, 16:00:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:00:27 INFO - 'tsvgr_opacity', 16:00:27 INFO - 'tart', 16:00:27 INFO - 'tscrollx', 16:00:27 INFO - 'cart']}, 16:00:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'talos_options': ['--disable-e10s'], 16:00:27 INFO - 'tests': ['tp5o']}, 16:00:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'tests': ['tp5o']}, 16:00:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'talos_options': ['--disable-e10s', 16:00:27 INFO - '--xperf_path', 16:00:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:27 INFO - 'tests': ['tp5n']}, 16:00:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:27 INFO - 'talos_options': ['--xperf_path', 16:00:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:27 INFO - 'tests': ['tp5n']}}, 16:00:27 INFO - 'talos.zip': {'path': '', 16:00:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:27 INFO - Running post-action listener: _resource_record_post_action 16:00:27 INFO - [mozharness: 2016-10-25 23:00:27.559955Z] Finished populate-webroot step (success) 16:00:27 INFO - [mozharness: 2016-10-25 23:00:27.560045Z] Running create-virtualenv step. 16:00:27 INFO - Running pre-action listener: _resource_record_pre_action 16:00:27 INFO - Running main action method: create_virtualenv 16:00:27 INFO - Creating virtualenv /builds/slave/test/build/venv 16:00:27 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 16:00:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:00:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:27 INFO - Using real prefix '/usr' 16:00:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:00:28 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:30 INFO - Installing pip.................done. 16:00:30 INFO - Return code: 0 16:00:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:00:30 INFO - Using env: {'DISPLAY': ':0', 16:00:30 INFO - 'HOME': '/home/cltbld', 16:00:30 INFO - 'LANG': 'en_US.UTF-8', 16:00:30 INFO - 'LANGUAGE': 'en_US:en', 16:00:30 INFO - 'LOGNAME': 'cltbld', 16:00:30 INFO - 'MAIL': '/var/mail/cltbld', 16:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:30 INFO - 'MOZ_NO_REMOTE': '1', 16:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:30 INFO - 'NO_EM_RESTART': '1', 16:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:30 INFO - 'PWD': '/builds/slave/test', 16:00:30 INFO - 'SHELL': '/bin/bash', 16:00:30 INFO - 'SHLVL': '1', 16:00:30 INFO - 'TERM': 'linux', 16:00:30 INFO - 'TMOUT': '86400', 16:00:30 INFO - 'USER': 'cltbld', 16:00:30 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:30 INFO - '_': '/tools/buildbot/bin/python'} 16:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:30 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 16:00:30 INFO - Cleaning up... 16:00:30 INFO - Return code: 0 16:00:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:00:30 INFO - Using env: {'DISPLAY': ':0', 16:00:30 INFO - 'HOME': '/home/cltbld', 16:00:30 INFO - 'LANG': 'en_US.UTF-8', 16:00:30 INFO - 'LANGUAGE': 'en_US:en', 16:00:30 INFO - 'LOGNAME': 'cltbld', 16:00:30 INFO - 'MAIL': '/var/mail/cltbld', 16:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:30 INFO - 'MOZ_NO_REMOTE': '1', 16:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:30 INFO - 'NO_EM_RESTART': '1', 16:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:30 INFO - 'PWD': '/builds/slave/test', 16:00:30 INFO - 'SHELL': '/bin/bash', 16:00:30 INFO - 'SHLVL': '1', 16:00:30 INFO - 'TERM': 'linux', 16:00:30 INFO - 'TMOUT': '86400', 16:00:30 INFO - 'USER': 'cltbld', 16:00:30 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:30 INFO - '_': '/tools/buildbot/bin/python'} 16:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:30 INFO - Downloading/unpacking psutil>=3.1.1 16:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:32 INFO - Installing collected packages: psutil 16:00:32 INFO - Running setup.py install for psutil 16:00:32 INFO - building 'psutil._psutil_linux' extension 16:00:32 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 16:00:32 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 16:00:32 INFO - building 'psutil._psutil_posix' extension 16:00:32 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 16:00:32 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 16:00:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:33 INFO - Successfully installed psutil 16:00:33 INFO - Cleaning up... 16:00:33 INFO - Return code: 0 16:00:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:00:33 INFO - Using env: {'DISPLAY': ':0', 16:00:33 INFO - 'HOME': '/home/cltbld', 16:00:33 INFO - 'LANG': 'en_US.UTF-8', 16:00:33 INFO - 'LANGUAGE': 'en_US:en', 16:00:33 INFO - 'LOGNAME': 'cltbld', 16:00:33 INFO - 'MAIL': '/var/mail/cltbld', 16:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:33 INFO - 'MOZ_NO_REMOTE': '1', 16:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:33 INFO - 'NO_EM_RESTART': '1', 16:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:33 INFO - 'PWD': '/builds/slave/test', 16:00:33 INFO - 'SHELL': '/bin/bash', 16:00:33 INFO - 'SHLVL': '1', 16:00:33 INFO - 'TERM': 'linux', 16:00:33 INFO - 'TMOUT': '86400', 16:00:33 INFO - 'USER': 'cltbld', 16:00:33 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:33 INFO - '_': '/tools/buildbot/bin/python'} 16:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:00:35 INFO - Installing collected packages: mozsystemmonitor 16:00:35 INFO - Running setup.py install for mozsystemmonitor 16:00:35 INFO - Successfully installed mozsystemmonitor 16:00:35 INFO - Cleaning up... 16:00:35 INFO - Return code: 0 16:00:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:00:35 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')]} 16:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:35 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')]} 16:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:35 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:35 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 16:00:35 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 16:00:35 INFO - Using env: {'DISPLAY': ':0', 16:00:35 INFO - 'HOME': '/home/cltbld', 16:00:35 INFO - 'LANG': 'en_US.UTF-8', 16:00:35 INFO - 'LANGUAGE': 'en_US:en', 16:00:35 INFO - 'LOGNAME': 'cltbld', 16:00:35 INFO - 'MAIL': '/var/mail/cltbld', 16:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:35 INFO - 'MOZ_NO_REMOTE': '1', 16:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:35 INFO - 'NO_EM_RESTART': '1', 16:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:35 INFO - 'PWD': '/builds/slave/test', 16:00:35 INFO - 'SHELL': '/bin/bash', 16:00:35 INFO - 'SHLVL': '1', 16:00:35 INFO - 'TERM': 'linux', 16:00:35 INFO - 'TMOUT': '86400', 16:00:35 INFO - 'USER': 'cltbld', 16:00:35 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:35 INFO - '_': '/tools/buildbot/bin/python'} 16:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:35 INFO - Downloading/unpacking jsonschema==2.5.1 16:00:35 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 16:00:35 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 16:00:35 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 16:00:35 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 16:00:35 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 16:00:35 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 16:00:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:00:37 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 16:00:37 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 16:00:37 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 16:00:37 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 16:00:37 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 16:00:37 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 16:00:37 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:37 INFO - warning: no files found matching '*.txt' 16:00:37 INFO - no previously-included directories found matching 'build' 16:00:37 INFO - no previously-included directories found matching 'dist' 16:00:37 INFO - no previously-included directories found matching '.git*' 16:00:37 INFO - Installing collected packages: jsonschema, functools32 16:00:37 INFO - Running setup.py install for functools32 16:00:38 INFO - warning: no files found matching '*.txt' 16:00:38 INFO - no previously-included directories found matching 'build' 16:00:38 INFO - no previously-included directories found matching 'dist' 16:00:38 INFO - no previously-included directories found matching '.git*' 16:00:38 INFO - Successfully installed jsonschema functools32 16:00:38 INFO - Cleaning up... 16:00:38 INFO - Return code: 0 16:00:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:00:38 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')]} 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 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')]} 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:38 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 16:00:38 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 16:00:38 INFO - Using env: {'DISPLAY': ':0', 16:00:38 INFO - 'HOME': '/home/cltbld', 16:00:38 INFO - 'LANG': 'en_US.UTF-8', 16:00:38 INFO - 'LANGUAGE': 'en_US:en', 16:00:38 INFO - 'LOGNAME': 'cltbld', 16:00:38 INFO - 'MAIL': '/var/mail/cltbld', 16:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:38 INFO - 'MOZ_NO_REMOTE': '1', 16:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:38 INFO - 'NO_EM_RESTART': '1', 16:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:38 INFO - 'PWD': '/builds/slave/test', 16:00:38 INFO - 'SHELL': '/bin/bash', 16:00:38 INFO - 'SHLVL': '1', 16:00:38 INFO - 'TERM': 'linux', 16:00:38 INFO - 'TMOUT': '86400', 16:00:38 INFO - 'USER': 'cltbld', 16:00:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:38 INFO - '_': '/tools/buildbot/bin/python'} 16:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:00:38 INFO - Cleaning up... 16:00:38 INFO - Return code: 0 16:00:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:00:38 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')]} 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 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')]} 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:38 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 16:00:38 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 16:00:38 INFO - Using env: {'DISPLAY': ':0', 16:00:38 INFO - 'HOME': '/home/cltbld', 16:00:38 INFO - 'LANG': 'en_US.UTF-8', 16:00:38 INFO - 'LANGUAGE': 'en_US:en', 16:00:38 INFO - 'LOGNAME': 'cltbld', 16:00:38 INFO - 'MAIL': '/var/mail/cltbld', 16:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:38 INFO - 'MOZ_NO_REMOTE': '1', 16:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:38 INFO - 'NO_EM_RESTART': '1', 16:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:38 INFO - 'PWD': '/builds/slave/test', 16:00:38 INFO - 'SHELL': '/bin/bash', 16:00:38 INFO - 'SHLVL': '1', 16:00:38 INFO - 'TERM': 'linux', 16:00:38 INFO - 'TMOUT': '86400', 16:00:38 INFO - 'USER': 'cltbld', 16:00:38 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:38 INFO - '_': '/tools/buildbot/bin/python'} 16:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:38 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:40 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:40 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 16:00:40 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 16:00:40 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 16:00:40 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 16:00:40 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 16:00:40 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 16:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:00:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:41 INFO - Downloading docopt-0.6.1.tar.gz 16:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:41 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:41 INFO - Running setup.py install for blobuploader 16:00:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:00:41 INFO - Running setup.py install for requests 16:00:41 INFO - Running setup.py install for docopt 16:00:41 INFO - Successfully installed blobuploader requests docopt 16:00:41 INFO - Cleaning up... 16:00:42 INFO - Return code: 0 16:00:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:42 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:42 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 16:00:42 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 16:00:42 INFO - Using env: {'DISPLAY': ':0', 16:00:42 INFO - 'HOME': '/home/cltbld', 16:00:42 INFO - 'LANG': 'en_US.UTF-8', 16:00:42 INFO - 'LANGUAGE': 'en_US:en', 16:00:42 INFO - 'LOGNAME': 'cltbld', 16:00:42 INFO - 'MAIL': '/var/mail/cltbld', 16:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:42 INFO - 'MOZ_NO_REMOTE': '1', 16:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:42 INFO - 'NO_EM_RESTART': '1', 16:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:42 INFO - 'PWD': '/builds/slave/test', 16:00:42 INFO - 'SHELL': '/bin/bash', 16:00:42 INFO - 'SHLVL': '1', 16:00:42 INFO - 'TERM': 'linux', 16:00:42 INFO - 'TMOUT': '86400', 16:00:42 INFO - 'USER': 'cltbld', 16:00:42 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:42 INFO - '_': '/tools/buildbot/bin/python'} 16:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:42 INFO - Running setup.py (path:/tmp/pip-FUuZqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:42 INFO - Running setup.py (path:/tmp/pip-mfYyZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:42 INFO - Running setup.py (path:/tmp/pip-p1bHvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:42 INFO - Running setup.py (path:/tmp/pip-V4hKLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:42 INFO - Running setup.py (path:/tmp/pip-Sc9qRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:42 INFO - Running setup.py (path:/tmp/pip-NIE5er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:42 INFO - Running setup.py (path:/tmp/pip-FzzTV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:42 INFO - Running setup.py (path:/tmp/pip-xNKve7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:42 INFO - Running setup.py (path:/tmp/pip-K_piI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:42 INFO - Running setup.py (path:/tmp/pip-nt5ztP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:42 INFO - Running setup.py (path:/tmp/pip-S9mxEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:43 INFO - Running setup.py (path:/tmp/pip-h8Otdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:43 INFO - Running setup.py (path:/tmp/pip-1Dxw9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:43 INFO - Running setup.py (path:/tmp/pip-_8iDtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:43 INFO - Running setup.py (path:/tmp/pip-PVHjx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:43 INFO - Running setup.py (path:/tmp/pip-TohfFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:43 INFO - Running setup.py (path:/tmp/pip-LPJIHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:43 INFO - Running setup.py install for manifestparser 16:00:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:00:43 INFO - Running setup.py install for mozcrash 16:00:43 INFO - Running setup.py install for mozdebug 16:00:43 INFO - Running setup.py install for mozdevice 16:00:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:00:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:00:43 INFO - Running setup.py install for mozfile 16:00:44 INFO - Running setup.py install for mozhttpd 16:00:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozinfo 16:00:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozInstall 16:00:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozleak 16:00:44 INFO - Running setup.py install for mozlog 16:00:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for moznetwork 16:00:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozprocess 16:00:44 INFO - Running setup.py install for mozprofile 16:00:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozrunner 16:00:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:00:44 INFO - Running setup.py install for mozscreenshot 16:00:45 INFO - Running setup.py install for moztest 16:00:45 INFO - Running setup.py install for mozversion 16:00:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:00:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:45 INFO - Cleaning up... 16:00:45 INFO - Return code: 0 16:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:45 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:45 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 16:00:45 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 16:00:45 INFO - Using env: {'DISPLAY': ':0', 16:00:45 INFO - 'HOME': '/home/cltbld', 16:00:45 INFO - 'LANG': 'en_US.UTF-8', 16:00:45 INFO - 'LANGUAGE': 'en_US:en', 16:00:45 INFO - 'LOGNAME': 'cltbld', 16:00:45 INFO - 'MAIL': '/var/mail/cltbld', 16:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:45 INFO - 'MOZ_NO_REMOTE': '1', 16:00:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:45 INFO - 'NO_EM_RESTART': '1', 16:00:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:45 INFO - 'PWD': '/builds/slave/test', 16:00:45 INFO - 'SHELL': '/bin/bash', 16:00:45 INFO - 'SHLVL': '1', 16:00:45 INFO - 'TERM': 'linux', 16:00:45 INFO - 'TMOUT': '86400', 16:00:45 INFO - 'USER': 'cltbld', 16:00:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:45 INFO - '_': '/tools/buildbot/bin/python'} 16:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:45 INFO - Running setup.py (path:/tmp/pip-2BlAso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:45 INFO - Running setup.py (path:/tmp/pip-4eYAqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:45 INFO - Running setup.py (path:/tmp/pip-PEAyzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:45 INFO - Running setup.py (path:/tmp/pip-ncoz99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:45 INFO - Running setup.py (path:/tmp/pip-rpCHUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:45 INFO - Running setup.py (path:/tmp/pip-alG8aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:45 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)) 16:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:45 INFO - Running setup.py (path:/tmp/pip-6OlAPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:46 INFO - Running setup.py (path:/tmp/pip-tFVouo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:46 INFO - Running setup.py (path:/tmp/pip-oWPdN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:46 INFO - Running setup.py (path:/tmp/pip-0C11ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:46 INFO - Running setup.py (path:/tmp/pip-VPLzjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:46 INFO - Running setup.py (path:/tmp/pip-U6SXL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:46 INFO - Running setup.py (path:/tmp/pip-v1IDQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:46 INFO - Running setup.py (path:/tmp/pip-D2ijUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:46 INFO - Running setup.py (path:/tmp/pip-jUqttH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:46 INFO - Running setup.py (path:/tmp/pip-zKrboE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:46 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)) 16:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:46 INFO - Running setup.py (path:/tmp/pip-WJE0ZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:46 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)) 16:00:46 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)) 16:00:46 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)) 16:00:46 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)) 16:00:46 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)) 16:00:46 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)) 16:00:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:46 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 16:00:46 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 16:00:46 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 16:00:46 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 16:00:46 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 16:00:46 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 16:00:48 INFO - Downloading blessings-1.6.tar.gz 16:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:48 INFO - Installing collected packages: blessings 16:00:48 INFO - Running setup.py install for blessings 16:00:48 INFO - Successfully installed blessings 16:00:48 INFO - Cleaning up... 16:00:48 INFO - Return code: 0 16:00:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:49 INFO - Reading from file tmpfile_stdout 16:00:49 INFO - Current package versions: 16:00:49 INFO - argparse == 1.2.1 16:00:49 INFO - blessings == 1.6 16:00:49 INFO - blobuploader == 1.2.4 16:00:49 INFO - docopt == 0.6.1 16:00:49 INFO - functools32 == 3.2.3-2 16:00:49 INFO - jsonschema == 2.5.1 16:00:49 INFO - manifestparser == 1.1 16:00:49 INFO - mozInstall == 1.12 16:00:49 INFO - mozcrash == 0.17 16:00:49 INFO - mozdebug == 0.1 16:00:49 INFO - mozdevice == 0.48 16:00:49 INFO - mozfile == 1.2 16:00:49 INFO - mozhttpd == 0.7 16:00:49 INFO - mozinfo == 0.9 16:00:49 INFO - mozleak == 0.1 16:00:49 INFO - mozlog == 3.3 16:00:49 INFO - moznetwork == 0.27 16:00:49 INFO - mozprocess == 0.23 16:00:49 INFO - mozprofile == 0.28 16:00:49 INFO - mozrunner == 6.12 16:00:49 INFO - mozscreenshot == 0.1 16:00:49 INFO - mozsystemmonitor == 0.3 16:00:49 INFO - moztest == 0.7 16:00:49 INFO - mozversion == 1.4 16:00:49 INFO - psutil == 3.1.1 16:00:49 INFO - requests == 1.2.3 16:00:49 INFO - wsgiref == 0.1.2 16:00:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:49 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:49 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 16:00:49 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 16:00:49 INFO - Using env: {'DISPLAY': ':0', 16:00:49 INFO - 'HOME': '/home/cltbld', 16:00:49 INFO - 'LANG': 'en_US.UTF-8', 16:00:49 INFO - 'LANGUAGE': 'en_US:en', 16:00:49 INFO - 'LOGNAME': 'cltbld', 16:00:49 INFO - 'MAIL': '/var/mail/cltbld', 16:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:49 INFO - 'MOZ_NO_REMOTE': '1', 16:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:49 INFO - 'NO_EM_RESTART': '1', 16:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:49 INFO - 'PWD': '/builds/slave/test', 16:00:49 INFO - 'SHELL': '/bin/bash', 16:00:49 INFO - 'SHLVL': '1', 16:00:49 INFO - 'TERM': 'linux', 16:00:49 INFO - 'TMOUT': '86400', 16:00:49 INFO - 'USER': 'cltbld', 16:00:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:49 INFO - '_': '/tools/buildbot/bin/python'} 16:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 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)) 16:00:49 INFO - Cleaning up... 16:00:49 INFO - Return code: 0 16:00:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:49 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 0x1a38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cf9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', '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 16:00:49 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 16:00:49 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 16:00:49 INFO - Using env: {'DISPLAY': ':0', 16:00:49 INFO - 'HOME': '/home/cltbld', 16:00:49 INFO - 'LANG': 'en_US.UTF-8', 16:00:49 INFO - 'LANGUAGE': 'en_US:en', 16:00:49 INFO - 'LOGNAME': 'cltbld', 16:00:49 INFO - 'MAIL': '/var/mail/cltbld', 16:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:49 INFO - 'MOZ_NO_REMOTE': '1', 16:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:49 INFO - 'NO_EM_RESTART': '1', 16:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:49 INFO - 'PWD': '/builds/slave/test', 16:00:49 INFO - 'SHELL': '/bin/bash', 16:00:49 INFO - 'SHLVL': '1', 16:00:49 INFO - 'TERM': 'linux', 16:00:49 INFO - 'TMOUT': '86400', 16:00:49 INFO - 'USER': 'cltbld', 16:00:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:49 INFO - '_': '/tools/buildbot/bin/python'} 16:00:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:49 INFO - Cleaning up... 16:00:49 INFO - Return code: 0 16:00:49 INFO - Running post-action listener: _resource_record_post_action 16:00:49 INFO - Running post-action listener: _start_resource_monitoring 16:00:49 INFO - Starting resource monitoring. 16:00:49 INFO - [mozharness: 2016-10-25 23:00:49.634018Z] Finished create-virtualenv step (success) 16:00:49 INFO - [mozharness: 2016-10-25 23:00:49.634502Z] Running install step. 16:00:49 INFO - Running pre-action listener: _resource_record_pre_action 16:00:49 INFO - Running main action method: install 16:00:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:49 INFO - Reading from file tmpfile_stdout 16:00:49 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:49 INFO - Reading from file tmpfile_stdout 16:00:49 INFO - Output received: 16:00:49 INFO - Usage: mozinstall [options] installer 16:00:49 INFO - Options: 16:00:49 INFO - -h, --help show this help message and exit 16:00:49 INFO - -d DEST, --destination=DEST 16:00:49 INFO - Directory to install application into. [default: 16:00:49 INFO - "/builds/slave/test"] 16:00:49 INFO - --app=APP Application being installed. [default: firefox] 16:00:49 INFO - mkdir: /builds/slave/test/build/application 16:00:49 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'] 16:00:49 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 16:01:04 INFO - Reading from file tmpfile_stdout 16:01:04 INFO - Output received: 16:01:04 INFO - /builds/slave/test/build/application/firefox/firefox 16:01:04 INFO - Running post-action listener: _resource_record_post_action 16:01:04 INFO - [mozharness: 2016-10-25 23:01:04.008580Z] Finished install step (success) 16:01:04 INFO - [mozharness: 2016-10-25 23:01:04.008860Z] Running run-tests step. 16:01:04 INFO - Running pre-action listener: _resource_record_pre_action 16:01:04 INFO - Running main action method: run_tests 16:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:01:04 INFO - Python 2.7.3 16:01:04 INFO - Return code: 0 16:01:04 INFO - grabbing minidump binary from tooltool 16:01:04 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')]} 16:01:04 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 0x1cf73b0>, '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 0x1cf82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cf8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:01:04 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 16:01:04 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 16:01:04 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 16:01:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:01:04 INFO - Return code: 0 16:01:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:01:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:01:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:01:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:01:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:01:04 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/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 16:01:04 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/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 16:01:04 INFO - Using env: {'DISPLAY': ':0', 16:01:04 INFO - 'HOME': '/home/cltbld', 16:01:04 INFO - 'LANG': 'en_US.UTF-8', 16:01:04 INFO - 'LANGUAGE': 'en_US:en', 16:01:04 INFO - 'LOGNAME': 'cltbld', 16:01:04 INFO - 'MAIL': '/var/mail/cltbld', 16:01:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:01:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:04 INFO - 'MOZ_NO_REMOTE': '1', 16:01:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:01:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:04 INFO - 'NO_EM_RESTART': '1', 16:01:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:04 INFO - 'PWD': '/builds/slave/test', 16:01:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:01:04 INFO - 'SHELL': '/bin/bash', 16:01:04 INFO - 'SHLVL': '1', 16:01:04 INFO - 'TERM': 'linux', 16:01:04 INFO - 'TMOUT': '86400', 16:01:04 INFO - 'USER': 'cltbld', 16:01:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642', 16:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:04 INFO - '_': '/tools/buildbot/bin/python'} 16:01:04 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/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 16:01:04 INFO - mozversion application_buildid: 20161025131024 16:01:04 INFO - mozversion application_changeset: c45f9369c95cb492a2dee21e9c5cefde192f573c 16:01:04 INFO - mozversion application_display_name: Firefox 16:01:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:01:04 INFO - mozversion application_name: Firefox 16:01:04 INFO - mozversion application_remotingname: firefox 16:01:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:01:04 INFO - mozversion application_vendor: Mozilla 16:01:04 INFO - mozversion application_version: 50.0 16:01:04 INFO - mozversion platform_buildid: 20161025131024 16:01:04 INFO - mozversion platform_changeset: c45f9369c95cb492a2dee21e9c5cefde192f573c 16:01:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:01:04 INFO - mozversion platform_version: 50.0 16:01:04 INFO - using testdate: 1477436464 16:01:04 INFO - actual date: 1477436464 16:01:04 INFO - starting webserver on 'localhost:40399' 16:01:04 INFO - SUITE-START | Running 2 tests 16:01:04 INFO - TEST-START | dromaeo_css 16:01:04 INFO - operating with platform_type : linux_ 16:01:04 INFO - Initialising browser for dromaeo_css test... 16:01:04 INFO - TEST-INFO | started process 19576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKVohVt/profile http://localhost:40399/getInfo.html) 16:01:08 INFO - PROCESS | 19576 | __metrics Screen width/height:1600/1200 16:01:08 INFO - PROCESS | 19576 | colorDepth:24 16:01:08 INFO - PROCESS | 19576 | Browser inner width/height: 1024/697 16:01:08 INFO - PROCESS | 19576 | __metrics 16:01:08 INFO - PROCESS | 19576 | 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" 16:01:08 INFO - PROCESS | 19576 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:01:09 INFO - TEST-INFO | 19576: exit 0 16:01:09 INFO - Browser initialized. 16:01:09 INFO - Running cycle 1/1 for dromaeo_css test... 16:01:09 INFO - TEST-INFO | started process 19664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKVohVt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:21 INFO - PROCESS | 19664 | RSS: Main: 144277504 16:01:21 INFO - PROCESS | 19664 | 16:02:12 INFO - PROCESS | 19664 | 1477436532803 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] 16:04:20 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40399/tests/dromaeo/cssquery-ext.html) 16:04:21 INFO - PROCESS | 19664 | RSS: Main: 262889472 16:04:21 INFO - PROCESS | 19664 | 16:07:24 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-ext.html (next: http://localhost:40399/tests/dromaeo/cssquery-jquery.html) 16:07:24 INFO - PROCESS | 19664 | RSS: Main: 237027328 16:07:24 INFO - PROCESS | 19664 | 16:10:26 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40399/tests/dromaeo/cssquery-mootools.html) 16:10:27 INFO - PROCESS | 19664 | RSS: Main: 177274880 16:10:27 INFO - PROCESS | 19664 | 16:13:29 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40399/tests/dromaeo/cssquery-prototype.html) 16:13:29 INFO - PROCESS | 19664 | RSS: Main: 200863744 16:13:29 INFO - PROCESS | 19664 | 16:16:32 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40399/tests/dromaeo/cssquery-yui.html) 16:16:32 INFO - PROCESS | 19664 | RSS: Main: 178307072 16:16:32 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | Cycle 1(1): loaded http://localhost:40399/tests/dromaeo/cssquery-yui.html (next: http://localhost:40399/tests/dromaeo/cssquery-dojo.html) 16:19:35 INFO - PROCESS | 19664 | RSS: Main: 261353472 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | __start_tp_report 16:19:35 INFO - PROCESS | 19664 | _x_x_mozilla_page_load 16:19:35 INFO - PROCESS | 19664 | _x_x_mozilla_page_load_details 16:19:35 INFO - PROCESS | 19664 | |i|pagename|runs| 16:19:35 INFO - PROCESS | 19664 | |0;dojo.html;3204.0548038328693;3216.9195770105744;3248.2204270974958;3271.2803183299666;3290.457074582694;5564.1931919871595;5654.123610840326;5675.5459563234945;5682.687452190133;5693.459121383472;5703.116017017369;5795.739191736371;5797.594168408203;5808.796692115777;5814.389489103636;5241.266222728821;5242.633015688884;5249.86875328117;5311.654742441749;5316.6544174628525;4986.725730084844;5088.389393272794;5330.067238233313;5331.600129990247;5336.039512887679;5035.144025515671;5122.667026643257;5131.102057140004;5140.383154021522;5165.870853228662;5150.150225212854;5167.173252279655;5176.741162941857;5179.7928082877015;5211.5830733541225;4926.064047830937;4971.801127954877;5046.44489106198;5069.949300507022;5135.64050516464;5494.230807686927;5554.694491802949;5579.832605021816;5583.972080139573;5623.915641265385;6000.939990600082;6035.396460353973;6047.516198704137;6048.516118710492;6049.758009679607;5817.185594016841;5852.795152169653;6120.387961203888;6126.540509461808;6131.969340153315;5566.666000040011;5652.463016013472;5653.23681303027;5655.717214139277;5693.77224911003;6183.783567575113;6186.628802271878;6192.752289908443;6195.442410183015;6198.876022479525;5709.714514274353;5940.910886336708;5947.1079338099635;5947.9702601486715;5966.045432730829;6044.172011125112;6178.4439400453275;6184.907226391608;6194.039923811816;6194.9690251549355;6140.478059365004;6174.351693072163;6176.042713379434;6180.103884936749;6184.196054512884;5921.111833224964;6003.879922401615;6186.133941248228;6188.195534580565;6191.597546159485;4625.5836974671765;4631.421072365954;4667.696599721007;4678;4736.384270044872;4152.647025002909;4157.168566286694;4159.521655009652;4160.18876319121;4177.039280965395;5881.588288819741;5896.705164741831;5900.468957793819;5922.733476993546;5924.526037917042;5981.37195594465;6060.969695151584;6121.418465245794;6123.877522449526;6164.938350616526;5824.388439213906;5867.354590994987;5868;5870.941290587125;5896.941030589639;5322.121849894801;5325.600579956519;5327.147656374961;5327.653702509325;5333.919991200135;5906.5570082244;6179.227596550431;6252.624842509373;6271.96864015677;6391.20109986261;6166.537509686702;6169.96915015422;6179.165812615419;6186.566940314315;6191.721372538156;6365.840853978688;6374.776882809079;6376.649284289409;6376.776812811529;6381.266154392304;5607.186957891165;5847.619904706181;5868.94131058701;5891.970540147272;6000.909986350295;6020.909686354796;6064.4541991221;6069.2716873975405;6074.271087469532;6075.483583895419;5857.326407463196;5910.970445147746;5914.378990205966;5921.171036055042;5921.851953701192;5605.075162598126;5766.769329226784;5837.328707198726;5845.824625261249;5959.642421454727;5841.065429531254;5841.94158058414;5852.4440178183;5854.092615644582;5856.677882716321;5923.703814809156;5932.525397968238;5942.108683697307;5946.762129514944;5949.226600542075;5609.467100625434;5851.795187168429;5858.970705146447;5880.323762767336;5906.586538942232 16:19:35 INFO - PROCESS | 19664 | |1;ext.html;584.9005669036263;630.4074170279937;641.1344684675688;650.1418128070945;657.3065415986133;34807;37758;44600.553994460046;45420;45492.54507454924;44709;45383.54616453835;45405;45450;45457;9148.58831352589;9302.348835581512;9305.860412093823;9311.860322095174;9313.95343023284;8444.071152173256;10729.570817167303;10979.83530247047;11029.669109926715;11344.886551134507;11190.272632278917;11195.776084478306;11253.718657033598;11298.604548840793;11301.717457063556;10782.73043173919;10853.294535855222;10859.511321990529;10862.45687715619;10864.674059778219;10925.399103049329;10970.067544258747;10987.39569323687;10989.835152472719;11002.889971100267;44208.778956105336;44365.77817110926;44376;44552.554474455486;44711.552884471064;3739.4577786221116;3760.811959402019;3811.4092315691114;3990.5610382857863;4438.800253988578;34511;34602.30795384103;34668;34874.82562587196;35275.76534821269;4652.74409907455;4722.1028004679;4768.308595253703;4773.618110551148;4847.684900481458;353816;353964;354279;354794;354951;59026;59114.70442647759;59667;59728.70135649294;59777;38811.41782873259;39670.80164599159;39738.80130599386;41870.1625967485;62503.37496624976;14205.147691138565;14362;14374.281285935662;14375.28123593837;14397.568072957618;10236.33463824849;10444.164466842634;10550.63072792464;10593.947030264953;10604.575816967235;19587.510312242022;20297.89851050735;20311.4922126948;20497;20550.486237843874;7390.852182956311;7557.886631700529;7567.977943380035;7592.202818704067;7683.807904802425;980.612658000086;1088.983665245022;1090.5092708281304;1152.4180288954024;1189.9524019039316;32524;33075.83462082674;33710;33864;34119.31761364759;1104.2160066352978;1118.5134466507095;1147.988520114805;1152.4122697424375;1247.2267194339568;16390.67218655644;17406.47780566585;17506.387276445264;17514.211860466054;17651;596199;605249;608578;608971;609359;62053.37946620566;62628.68685656542;62829.05756413657;62854.68572657199;62877;1312.1536608887213;1380.3098450774612;1418.44680574576;1430.9212993285678;1437.6837095838898;33460.83269583636;34505.48241776426;35481.46777798387;35551.82224088863;35570.64429355673;53388.73305633447;53863;54127.72936135294;54178;54179.187312191134;440852;445841;446113;446644;449230;27309.726902731512;29647.851760741058;30627.54058689077;30659.846700766353;32209;49684.75157624333;50371;50502.747486262335;50716;50829.49170508396;42219;42510.1497970045;43484.130317394105;43500.12999740051;44984.77507612572;44082.77958610187;44091.77954110209;44217.77891110653;45219.321710175034;48392.758036211;43333.133337332445;43984.34023489586;44303;44446.55553444424;44463;36888;38717.419238712006;39152;39824.80087599543;39875.60124398835;28949.276268093465;29005.709942901143;29562.260943476584;29591.556126658546;29603.259918502208 16:19:35 INFO - PROCESS | 19664 | |2;jquery.html;415.49102349621717;422.5056683680094;431.50592571505615;432.2414163143683;435.91935491933975;18970.336038238664;19571.706424403626;20337.898310508426;20543.486412839688;20609;19564.510887227792;19693.50766230845;19851.900740496276;20387.490312742153;20486;5765.942340576593;5968.970155149218;5975.25309336333;5980.342162362137;5984.401559844003;5905.793297234576;5915.260592425947;5934;5969.104634304846;5974.611650242742;5795.507381872546;5925.614835035731;5936.5250779937505;5944.167816505671;5951.464368206861;5857.062869940811;5874.089516125007;5895.941040589582;5911.408859114097;5938.198343223674;5344.759485823147;5345.385280692732;5348.5186333230195;5350.197470379435;5354.892902141935;23253.651195232083;24503.142390016263;24530.754692453025;24731.876340618182;24851;2606.1660268714018;2624.448865738197;2817.1970988268245;2817.8450185239803;2973.4499117663086;14813.55559333211;15089.170095644846;15095.320710567941;15480.922595387063;15529;1802.774653168354;1998.7001949707515;2031.1489277006547;2093.884836334001;2108.5572029873747;64952.675236623516;66657.66671166613;78163;86916.5654171725;100520.49739751255;17837.28650853977;18271.26914923388;18686.532836678918;18982.620347592972;19277.036148192536;33401;33582.16044598856;33717;33911.49132763011;34386.48420273698;9476.386447179204;9532.285078619132;9577.377470464398;9679.177269932135;10242.334248273988;7075.327843854826;7624.42816788743;7821;7825.178356272623;7960.761177164694;9296.814063718823;9400;9462.57418416159;9502.524873756285;9613.230941524662;3549.2723991581665;3585.229175727179;3691.187938653492;3796.6583007529457;3951.3677811550015;819.8565251081156;938.9108034736689;985.049427302642;1037.3464717228098;1079.9082078023457;19754.506137346394;19810;20161.39515814528;20298.18807247723;20848.687269690967;1007.496251874063;1016.3850870223408;1017.4251547875426;1054.4306074719566;1090.0571006079683;10803.297785644065;11358.636963564426;11441.885581144248;11445.656630300939;11511.424428778528;547109;547521;556547;558060;558272;94603.52698236465;97117;99596.50201748945;99711.50144249233;102427;969.3117886300805;1004.9598016079422;1021.1065317846735;1034.5137785240925;1034.9689509314733;17550.473485795446;18066.367677131762;18081.457556273333;18499.630007399508;18510.629787404447;43412.348814767174;44134.55865441304;45020;45747;46170.307445389015;61515.692421537606;62297.75404491794;63042.68478657577;63552.36447635465;63897.36102638914;9009.144131307516;9290.674826381315;9415.10556497159;9504.619815207314;9528.761780955254;12722.872771272168;13314.733705326033;13860.792088118887;13953.302334883012;14029.438822446988;11355.602553910574;11484.770304594029;11599.130065244972;11646.184767066225;12028.518859245532;11704.356260405419;12100.45547950362;12248.203866748632;12279.938600306941;12607.49570017189;11752.471138798946;11797.646070617895;11841.940790295994;11860.644180674593;11865.110116741107;9352.579133938852;9368.672096476863;9375.906240937504;9450.621975120917;9477.857832132386;11426.885731142582;11595.768084638428;11725;11815.940920295343;12162 16:19:35 INFO - PROCESS | 19664 | |3;mootools.html;1386.4939297156538;1478.4160256698603;1588.610790356363;1776.573622330641;1811.4475085099039;8939.508327042011;9525.714228573137;9537.093976072261;9549.809003819919;9551.61793528259;7972.601369931509;8965.641374345018;9178.449293042422;9233.538323083852;9325.81348373032;2514.7736703696664;2561.8206725529126;2565.8973641054336;2566.7304932982047;2572.65269188659;2521.621756736486;2566.1788227767124;2567.396661784479;2567.9101231456902;2571.472848066142;2472.11004038546;2492.401823562341;2565.4484285878534;2565.525377805112;2566.396896729267;2505.6742623458927;2518.106072344319;2518.4837108392826;2545.376382786211;2550.0437335999;2516.094206085808;2517.647529345893;2526.5199612073648;2528.102523604131;2547.6305935639416;9342.67300644481;9577.808443831154;9580.377275477073;9590.42457452555;9591.328606997513;5762.827115186509;5924.763009479616;5929.436703513203;5935.228420305332;5936.228290322274;3619.402798538238;3774.5281839770028;3788.583255841883;3793.3551296279425;3832.061145019488;1200.639808057588;1244.402686710375;1249.1256120715445;1252.167308739687;1277.8083287506856;13177.934110329386;13262.53581124656;13308;13365.599032029055;13396.799048014385;5097.082525145435;5102.719350435687;5109;5149.124648809711;5311.840644780663;11344.829827552594;11377.943110284396;11390.772184556263;11394.94302528482;11403.087752979742;5878.382769809108;5892.793752218738;5918.230630018156;5919.378465261172;5922.733476993546;4652.860414187579;5239.711815850165;5332.093544097513;5382.838514844561;5442.836714898559;4399.802008909607;4459.643228541774;4505.6846020778885;4546.727196368229;4678.345031695565;1393.9512117075853;1452.4045141491938;1455.2287287737515;1470.4191844221473;1477.8448262932238;884.6196135661598;940.9012053734259;1002.9548670309854;1003.2325271167563;1008.3798463944621;6068.423499767515;6181;6303.275123360872;6304.590201636879;6396.456301214449;484.29051439641097;491.53058828818394;498.7755510020505;501.02800566900095;504.19580768673904;4215.199112168678;4278.315469524923;4281.743095414286;4286.65706743466;4295.183915056129;11996.220245684002;12493.812592811115;12523.123381363399;12529.561465348852;12562.183458075378;12446.25322480654;12464.376781160905;12481.063920206021;12493.687657808627;12504.874951250371;8703.738887833375;9049.864252036225;9051.637934482547;9222;9229.307801914883;6032.607880487754;6166.537509686702;6230.190075290183;6280.120783090462;6383.457406120346;11196.888031119584;11283.605073822706;11472;11700;11779;11102.222844400814;11341.489632966372;11546.422678865854;11744.060475161796;11865.5847045353;7652.885206722015;8224.671013159406;8413.70552030676;8514.191151840565;8569.10024447449;9339.112784285482;9426.245900328095;9446.763830904283;9455;9460.621575136918;8379.790505237417;8479.024912134937;8507.149285071691;8590.1839325264;8733.65065397377;7979.880301795594;8166.42835001568;8224.103063440845;8294.626741796754;8495.74512764618;7098.503104782616;7142.393351081082;7154.463415243774;7159.284071592799;7217.170025447141;4962.181240095489;4991.675541089818;5036.169032109808;5052.115879721107;5085.211792172219;5172.224166375074;5261;5352.812651557177;5397.298351214317;5410.431904649955 16:19:35 INFO - PROCESS | 19664 | |4;prototype.html;347.3348537550591;354.4895350694999;357.0912028886484;359.3513707757497;364.3896473407044;5509.256250406194;5556.277683901092;5578.972105139469;5583.911137328223;5590.748416321265;5157.355330583677;5301.496357846008;5308.973455132719;5312.867178320544;5492.395836457997;4252.808623611945;4318.460192475941;4367.737935723851;4408.5591440855815;4409.001878722467;4345.500267469234;4348.369486424466;4350.195213885426;4362.432883725127;4405.82376704933;4157.355609880458;4291.549387314319;4305.892352691192;4318.740875547462;4379.802908869108;4285.44289242398;4288.764117973495;4313.568643135662;4317;4330.7401555906745;3817.1220619257724;3853.2100919311756;3859.826307816155;3861.0694652673665;3863.8261278242267;5218.895622087575;5319.9734001330135;5393.460653934614;5405.972970135124;5417.349918009824;4942.9258561121615;4974.801007959677;4987.700737955698;4991.101601711691;5004.099262132815;5688.829335119953;5695.971520142372;5720.1991721159075;5750.367459579443;5779.739911703983;4115.300398932189;4169.97915010423;4194.412782210493;4207.705460617758;4224.978875105636;5861.20873682056;5909.699866029467;5921.111833225008;5948.018576934801;5952.613080149819;4288.978555107205;4293.313069908862;4326.24290749124;4335.154644844288;4336.956630433719;4275.144971005749;4336.522982471925;4368.257396242647;4378.956210437855;4380.211561918822;4895.6083513318845;4905.484924082992;4908.386451693538;4920.3603531541385;4922.926156107661;5776.624519406225;5787.466321424826;5792.725600367912;5800.854978625568;5846.649201047866;1399.9290542734611;1447.4572035486692;1460.7662773956115;1485.843986381436;1507.2991059157544;1019.1235537437798;1021.1728499914944;1041.401194313273;1046.6598355534481;1048.496721573649;5545.611807173459;5560.777568897199;5605.421506107236;5621.831345059655;5625;5812.447817457335;5816.905913871003;5821.446962538552;5874.676892770939;5880.206172166788;5399.8687324727925;5527.502524772709;5541.168824676331;5552.639078459887;5571.442855714477;2731.7677997370447;2751.834889906612;2763.231531819637;2765.5575107983027;2781.7635500982237;5272.151329529388;5286.779552587922;5305.681659100466;5348.545373643206;5406.648567843156;4912.9508704913205;5130.4356520782685;5183.663061900965;5197.84406467799;5224.425313215544;5232.476752324737;5280.445553216934;5280.9471905281225;5302.92045619316;5331.027519817615;5125.487451254845;5144.228365745168;5150.845474635767;5164.690118592896;5241.554467870274;4860.562549370574;5085.211792172219;5091.592672586258;5097.490250974873;5130.461301563282;5262.447443018428;5313.229581710553;5340.439253878442;5350.705711185922;5370.62405631602;3851.9792255051875;3859.3824988001784;3894.812082314808;3903.765774053566;3923.862664806718;4842.394700662418;5127.974360128175;5140.948590514047;5153.819616313411;5175.948240517697;5526.005319042611;5658.660480371191;5709.885802283848;5767.769289228551;5768.307803063659;5750.25246717924;5760;5760.473474529337;5761.740721667451;5785;5618.634788738719;5659.886802264014;5663.423142470084;5712.942870571407;5720.227769251098;5736.913946290725;5773.682447465262;5782.508486778671;5796.507296879644;5796.652200867961;5626.437356264504;5654.180143879197;5656.745446454837;5696.487316141567;5743.0811070228565 16:19:35 INFO - PROCESS | 19664 | |5;yui.html;795.1650766694972;816.4978538199009;818.0960039156731;822.8806823010661;828.7555171224489;16252.024878507284;16672.74990875135;16858.49424517265;16865.66268674626;17683.64632707345;16251.593710157222;16380.83619163808;16390.508284751446;16391.180440977932;16410.425635102773;717.6734585763465;836.6277006732007;851.1998721202082;872.1845074854999;877.4515927545284;857.5412154497315;863.9179277968583;865.9263962563158;868.1231955724701;869.491347561674;913.9771505712343;915.2860768600469;915.6703586708779;917.7659696777346;921.3274309753883;910.4855756497558;913.5340976102202;915.6108653822132;916.9546716742918;917.0279503726039;667.6361383046251;669.9430548403343;672.7914346552584;673.2056173715014;674.1943377663694;16841.494755157364;16919.830801691947;17077.060761658104;17138.31446742129;17166.742498862528;15991.440299589574;16301.429449969313;16663.25015374311;16722.916385417993;16904;13151;13296.867031329659;13372.531961381304;13453.66365840852;13484.190948543119;4029.6977726670325;4041.1109555897656;4116.732412393185;4121;4248.830046798124;81598.59200704018;82042;82604.17395826086;83856.58071709663;84750;11661.24201926889;12016.699582510333;12137.817932731015;12338.938305308417;12472.43874025671;18925.24299028051;18958.52603684919;18978.14598343078;18978.62042759137;18998.620027599372;8683.131686831266;8689;8848.867266991001;8969.416987895765;9087.81824363509;5145.331106956071;5155.510226528474;5165.1219292720325;5241.24002019705;5449.128139497661;3903.590123037097;4045.7167998239843;4059.127287633173;4061.289274376972;4063.045184381668;659.035726673602;669.1535207961932;672.9495287853431;676.036647776916;679.9626020568817;641.7208514296296;656.448583190113;754.4680999895061;789.4710543935577;808.720991258015;9638.518074096268;10487.790244195074;10523.421211833424;10950.34297942126;10975.012248897478;626.674129452682;732.7948174511146;751.6467260387609;772.4708574626399;780.42248735935;5057.645964782503;5225.005597154203;5369.114096174166;5390.380106287748;5444.537214336747;77135.61432192916;106655.9334406646;131921;135588;138666.30666846802;68570.65714671394;78609;113515;114620;118068;60276.39723602796;63815.680921596024;88453.55773221221;96817.51591241999;99386;10069.043440873105;10086.646967356108;10367.84448233262;10447.634332798316;10516.842247366143;57581.71209143927;57866.7106664464;59186;60499;60866;77006;83856;87174.56412717896;88040;89755.55122224348;12138.696532586757;12510;12841.101122921305;14073.296335182995;14170.149791012573;15574.299156538209;16359.754603680716;17222.224999874787;17246.137693115546;17691;15465.458708945584;15811.683766324839;16405.179741012664;16679.166041697623;16924.830751692327;14452.494162704255;14665.340059697126;14773.852261477678;14837.10977341363;14991.100533967996;13490.662733431744;13870.098443601133;14747.483838065617;14861.182634955181;15073.472428464951;11684.937834978304;12061.939690301844;12673.556425525063;12782.68043298925;12989.155704879153;12272.140950133762;12272.877271227531;12300.50797968107;12361.876381236072;12733.29966851832 16:19:35 INFO - PROCESS | 19664 | __end_tp_report 16:19:35 INFO - PROCESS | 19664 | __start_cc_report 16:19:35 INFO - PROCESS | 19664 | _x_x_mozilla_cycle_collect,979 16:19:35 INFO - PROCESS | 19664 | __end_cc_report 16:19:35 INFO - PROCESS | 19664 | __startTimestamp1477437575589__endTimestamp 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | ------- Summary: start ------- 16:19:35 INFO - PROCESS | 19664 | Number of tests: 6 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#0] dojo.html Cycles:165 Average:5644.58 Median:5857.33 stddev:646.28 (11.0%) stddev-sans-first:619.26 16:19:35 INFO - PROCESS | 19664 | Values: 3204.1 3216.9 3248.2 3271.3 3290.5 5564.2 5654.1 5675.5 5682.7 5693.5 5703.1 5795.7 5797.6 5808.8 5814.4 5241.3 5242.6 5249.9 5311.7 5316.7 4986.7 5088.4 5330.1 5331.6 5336.0 5035.1 5122.7 5131.1 5140.4 5165.9 5150.2 5167.2 5176.7 5179.8 5211.6 4926.1 4971.8 5046.4 5069.9 5135.6 5494.2 5554.7 5579.8 5584.0 5623.9 6000.9 6035.4 6047.5 6048.5 6049.8 5817.2 5852.8 6120.4 6126.5 6132.0 5566.7 5652.5 5653.2 5655.7 5693.8 6183.8 6186.6 6192.8 6195.4 6198.9 5709.7 5940.9 5947.1 5948.0 5966.0 6044.2 6178.4 6184.9 6194.0 6195.0 6140.5 6174.4 6176.0 6180.1 6184.2 5921.1 6003.9 6186.1 6188.2 6191.6 4625.6 4631.4 4667.7 4678.0 4736.4 4152.6 4157.2 4159.5 4160.2 4177.0 5881.6 5896.7 5900.5 5922.7 5924.5 5981.4 6061.0 6121.4 6123.9 6164.9 5824.4 5867.4 5868.0 5870.9 5896.9 5322.1 5325.6 5327.1 5327.7 5333.9 5906.6 6179.2 6252.6 6272.0 6391.2 6166.5 6170.0 6179.2 6186.6 6191.7 6365.8 6374.8 6376.6 6376.8 6381.3 5607.2 5847.6 5868.9 5892.0 6000.9 6020.9 6064.5 6069.3 6074.3 6075.5 5857.3 5911.0 5914.4 5921.2 5921.9 5605.1 5766.8 5837.3 5845.8 5959.6 5841.1 5841.9 5852.4 5854.1 5856.7 5923.7 5932.5 5942.1 5946.8 5949.2 5609.5 5851.8 5859.0 5880.3 5906.6 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#1] ext.html Cycles:180 Average:63277.78 Median:38981.71 stddev:127516.10 (327.1%) stddev-sans-first:127786.94 16:19:35 INFO - PROCESS | 19664 | Values: 584.9 630.4 641.1 650.1 657.3 34807.0 37758.0 44600.6 45420.0 45492.5 44709.0 45383.5 45405.0 45450.0 45457.0 9148.6 9302.3 9305.9 9311.9 9314.0 8444.1 10729.6 10979.8 11029.7 11344.9 11190.3 11195.8 11253.7 11298.6 11301.7 10782.7 10853.3 10859.5 10862.5 10864.7 10925.4 10970.1 10987.4 10989.8 11002.9 44208.8 44365.8 44376.0 44552.6 44711.6 3739.5 3760.8 3811.4 3990.6 4438.8 34511.0 34602.3 34668.0 34874.8 35275.8 4652.7 4722.1 4768.3 4773.6 4847.7 353816.0 353964.0 354279.0 354794.0 354951.0 59026.0 59114.7 59667.0 59728.7 59777.0 38811.4 39670.8 39738.8 41870.2 62503.4 14205.1 14362.0 14374.3 14375.3 14397.6 10236.3 10444.2 10550.6 10593.9 10604.6 19587.5 20297.9 20311.5 20497.0 20550.5 7390.9 7557.9 7568.0 7592.2 7683.8 980.6 1089.0 1090.5 1152.4 1190.0 32524.0 33075.8 33710.0 33864.0 34119.3 1104.2 1118.5 1148.0 1152.4 1247.2 16390.7 17406.5 17506.4 17514.2 17651.0 596199.0 605249.0 608578.0 608971.0 609359.0 62053.4 62628.7 62829.1 62854.7 62877.0 1312.2 1380.3 1418.4 1430.9 1437.7 33460.8 34505.5 35481.5 35551.8 35570.6 53388.7 53863.0 54127.7 54178.0 54179.2 440852.0 445841.0 446113.0 446644.0 449230.0 27309.7 29647.9 30627.5 30659.8 32209.0 49684.8 50371.0 50502.7 50716.0 50829.5 42219.0 42510.1 43484.1 43500.1 44984.8 44082.8 44091.8 44217.8 45219.3 48392.8 43333.1 43984.3 44303.0 44446.6 44463.0 36888.0 38717.4 39152.0 39824.8 39875.6 28949.3 29005.7 29562.3 29591.6 29603.3 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#2] jquery.html Cycles:180 Average:32458.56 Median:33649.58 stddev:90930.32 (270.2%) stddev-sans-first:91153.57 16:19:35 INFO - PROCESS | 19664 | Values: 415.5 422.5 431.5 432.2 435.9 18970.3 19571.7 20337.9 20543.5 20609.0 19564.5 19693.5 19851.9 20387.5 20486.0 5765.9 5969.0 5975.3 5980.3 5984.4 5905.8 5915.3 5934.0 5969.1 5974.6 5795.5 5925.6 5936.5 5944.2 5951.5 5857.1 5874.1 5895.9 5911.4 5938.2 5344.8 5345.4 5348.5 5350.2 5354.9 23253.7 24503.1 24530.8 24731.9 24851.0 2606.2 2624.4 2817.2 2817.8 2973.4 14813.6 15089.2 15095.3 15480.9 15529.0 1802.8 1998.7 2031.1 2093.9 2108.6 64952.7 66657.7 78163.0 86916.6 100520.5 17837.3 18271.3 18686.5 18982.6 19277.0 33401.0 33582.2 33717.0 33911.5 34386.5 9476.4 9532.3 9577.4 9679.2 10242.3 7075.3 7624.4 7821.0 7825.2 7960.8 9296.8 9400.0 9462.6 9502.5 9613.2 3549.3 3585.2 3691.2 3796.7 3951.4 819.9 938.9 985.0 1037.3 1079.9 19754.5 19810.0 20161.4 20298.2 20848.7 1007.5 1016.4 1017.4 1054.4 1090.1 10803.3 11358.6 11441.9 11445.7 11511.4 547109.0 547521.0 556547.0 558060.0 558272.0 94603.5 97117.0 99596.5 99711.5 102427.0 969.3 1005.0 1021.1 1034.5 1035.0 17550.5 18066.4 18081.5 18499.6 18510.6 43412.3 44134.6 45020.0 45747.0 46170.3 61515.7 62297.8 63042.7 63552.4 63897.4 9009.1 9290.7 9415.1 9504.6 9528.8 12722.9 13314.7 13860.8 13953.3 14029.4 11355.6 11484.8 11599.1 11646.2 12028.5 11704.4 12100.5 12248.2 12279.9 12607.5 11752.5 11797.6 11841.9 11860.6 11865.1 9352.6 9368.7 9375.9 9450.6 9477.9 11426.9 11595.8 11725.0 11815.9 12162.0 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#3] mootools.html Cycles:180 Average:6310.02 Median:2726.86 stddev:3685.99 (135.2%) stddev-sans-first:3677.75 16:19:35 INFO - PROCESS | 19664 | Values: 1386.5 1478.4 1588.6 1776.6 1811.4 8939.5 9525.7 9537.1 9549.8 9551.6 7972.6 8965.6 9178.4 9233.5 9325.8 2514.8 2561.8 2565.9 2566.7 2572.7 2521.6 2566.2 2567.4 2567.9 2571.5 2472.1 2492.4 2565.4 2565.5 2566.4 2505.7 2518.1 2518.5 2545.4 2550.0 2516.1 2517.6 2526.5 2528.1 2547.6 9342.7 9577.8 9580.4 9590.4 9591.3 5762.8 5924.8 5929.4 5935.2 5936.2 3619.4 3774.5 3788.6 3793.4 3832.1 1200.6 1244.4 1249.1 1252.2 1277.8 13177.9 13262.5 13308.0 13365.6 13396.8 5097.1 5102.7 5109.0 5149.1 5311.8 11344.8 11377.9 11390.8 11394.9 11403.1 5878.4 5892.8 5918.2 5919.4 5922.7 4652.9 5239.7 5332.1 5382.8 5442.8 4399.8 4459.6 4505.7 4546.7 4678.3 1394.0 1452.4 1455.2 1470.4 1477.8 884.6 940.9 1003.0 1003.2 1008.4 6068.4 6181.0 6303.3 6304.6 6396.5 484.3 491.5 498.8 501.0 504.2 4215.2 4278.3 4281.7 4286.7 4295.2 11996.2 12493.8 12523.1 12529.6 12562.2 12446.3 12464.4 12481.1 12493.7 12504.9 8703.7 9049.9 9051.6 9222.0 9229.3 6032.6 6166.5 6230.2 6280.1 6383.5 11196.9 11283.6 11472.0 11700.0 11779.0 11102.2 11341.5 11546.4 11744.1 11865.6 7652.9 8224.7 8413.7 8514.2 8569.1 9339.1 9426.2 9446.8 9455.0 9460.6 8379.8 8479.0 8507.1 8590.2 8733.7 7979.9 8166.4 8224.1 8294.6 8495.7 7098.5 7142.4 7154.5 7159.3 7217.2 4962.2 4991.7 5036.2 5052.1 5085.2 5172.2 5261.0 5352.8 5397.3 5410.4 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#4] prototype.html Cycles:180 Average:4693.77 Median:5170.32 stddev:1340.11 (25.9%) stddev-sans-first:1303.56 16:19:35 INFO - PROCESS | 19664 | Values: 347.3 354.5 357.1 359.4 364.4 5509.3 5556.3 5579.0 5583.9 5590.7 5157.4 5301.5 5309.0 5312.9 5492.4 4252.8 4318.5 4367.7 4408.6 4409.0 4345.5 4348.4 4350.2 4362.4 4405.8 4157.4 4291.5 4305.9 4318.7 4379.8 4285.4 4288.8 4313.6 4317.0 4330.7 3817.1 3853.2 3859.8 3861.1 3863.8 5218.9 5320.0 5393.5 5406.0 5417.3 4942.9 4974.8 4987.7 4991.1 5004.1 5688.8 5696.0 5720.2 5750.4 5779.7 4115.3 4170.0 4194.4 4207.7 4225.0 5861.2 5909.7 5921.1 5948.0 5952.6 4289.0 4293.3 4326.2 4335.2 4337.0 4275.1 4336.5 4368.3 4379.0 4380.2 4895.6 4905.5 4908.4 4920.4 4922.9 5776.6 5787.5 5792.7 5800.9 5846.6 1399.9 1447.5 1460.8 1485.8 1507.3 1019.1 1021.2 1041.4 1046.7 1048.5 5545.6 5560.8 5605.4 5621.8 5625.0 5812.4 5816.9 5821.4 5874.7 5880.2 5399.9 5527.5 5541.2 5552.6 5571.4 2731.8 2751.8 2763.2 2765.6 2781.8 5272.2 5286.8 5305.7 5348.5 5406.6 4913.0 5130.4 5183.7 5197.8 5224.4 5232.5 5280.4 5280.9 5302.9 5331.0 5125.5 5144.2 5150.8 5164.7 5241.6 4860.6 5085.2 5091.6 5097.5 5130.5 5262.4 5313.2 5340.4 5350.7 5370.6 3852.0 3859.4 3894.8 3903.8 3923.9 4842.4 5128.0 5140.9 5153.8 5175.9 5526.0 5658.7 5709.9 5767.8 5768.3 5750.3 5760.0 5760.5 5761.7 5785.0 5618.6 5659.9 5663.4 5712.9 5720.2 5736.9 5773.7 5782.5 5796.5 5796.7 5626.4 5654.2 5656.7 5696.5 5743.1 16:19:35 INFO - PROCESS | 19664 | 16:19:35 INFO - PROCESS | 19664 | [#5] yui.html Cycles:180 Average:22018.45 Median:4060.21 stddev:31468.36 (775.0%) stddev-sans-first:31516.29 16:19:35 INFO - PROCESS | 19664 | Values: 795.2 816.5 818.1 822.9 828.8 16252.0 16672.7 16858.5 16865.7 17683.6 16251.6 16380.8 16390.5 16391.2 16410.4 717.7 836.6 851.2 872.2 877.5 857.5 863.9 865.9 868.1 869.5 914.0 915.3 915.7 917.8 921.3 910.5 913.5 915.6 917.0 917.0 667.6 669.9 672.8 673.2 674.2 16841.5 16919.8 17077.1 17138.3 17166.7 15991.4 16301.4 16663.3 16722.9 16904.0 13151.0 13296.9 13372.5 13453.7 13484.2 4029.7 4041.1 4116.7 4121.0 4248.8 81598.6 82042.0 82604.2 83856.6 84750.0 11661.2 12016.7 12137.8 12338.9 12472.4 18925.2 18958.5 18978.1 18978.6 18998.6 8683.1 8689.0 8848.9 8969.4 9087.8 5145.3 5155.5 5165.1 5241.2 5449.1 3903.6 4045.7 4059.1 4061.3 4063.0 659.0 669.2 672.9 676.0 680.0 641.7 656.4 754.5 789.5 808.7 9638.5 10487.8 10523.4 10950.3 10975.0 626.7 732.8 751.6 772.5 780.4 5057.6 5225.0 5369.1 5390.4 5444.5 77135.6 106655.9 131921.0 135588.0 138666.3 68570.7 78609.0 113515.0 114620.0 118068.0 60276.4 63815.7 88453.6 96817.5 99386.0 10069.0 10086.6 10367.8 10447.6 10516.8 57581.7 57866.7 59186.0 60499.0 60866.0 77006.0 83856.0 87174.6 88040.0 89755.6 12138.7 12510.0 12841.1 14073.3 14170.1 15574.3 16359.8 17222.2 17246.1 17691.0 15465.5 15811.7 16405.2 16679.2 16924.8 14452.5 14665.3 14773.9 14837.1 14991.1 13490.7 13870.1 14747.5 14861.2 15073.5 11684.9 12061.9 12673.6 12782.7 12989.2 12272.1 12272.9 12300.5 12361.9 12733.3 16:19:35 INFO - PROCESS | 19664 | -------- Summary: end -------- 16:19:35 INFO - PROCESS | 19664 | 16:19:36 INFO - TEST-INFO | 19664: exit 0 16:19:36 INFO - TEST-OK | dromaeo_css | took 1112086ms 16:19:36 INFO - TEST-START | kraken 16:19:36 INFO - operating with platform_type : linux_ 16:19:36 INFO - Initialising browser for kraken test... 16:19:36 INFO - TEST-INFO | started process 19867 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHbmW1w/profile http://localhost:40399/getInfo.html) 16:19:40 INFO - PROCESS | 19867 | __metrics Screen width/height:1600/1200 16:19:40 INFO - PROCESS | 19867 | colorDepth:24 16:19:40 INFO - PROCESS | 19867 | Browser inner width/height: 1024/697 16:19:40 INFO - PROCESS | 19867 | __metrics 16:19:40 INFO - PROCESS | 19867 | 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" 16:19:40 INFO - PROCESS | 19867 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:41 INFO - TEST-INFO | 19867: exit 0 16:19:41 INFO - Browser initialized. 16:19:41 INFO - Running cycle 1/1 for kraken test... 16:19:41 INFO - TEST-INFO | started process 19958 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHbmW1w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:53 INFO - PROCESS | 19958 | RSS: Main: 144089088 16:19:53 INFO - PROCESS | 19958 | 16:19:55 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40399/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:56 INFO - PROCESS | 19958 | RSS: Main: 199221248 16:19:56 INFO - PROCESS | 19958 | 16:19:59 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40399/tests/kraken/driver.html?testName=audio-dft) 16:19:59 INFO - PROCESS | 19958 | RSS: Main: 183734272 16:19:59 INFO - PROCESS | 19958 | 16:20:02 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40399/tests/kraken/driver.html?testName=audio-fft) 16:20:03 INFO - PROCESS | 19958 | RSS: Main: 275857408 16:20:03 INFO - PROCESS | 19958 | 16:20:05 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40399/tests/kraken/driver.html?testName=audio-oscillator) 16:20:06 INFO - PROCESS | 19958 | RSS: Main: 180146176 16:20:06 INFO - PROCESS | 19958 | 16:20:08 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40399/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:08 INFO - PROCESS | 19958 | RSS: Main: 179433472 16:20:08 INFO - PROCESS | 19958 | 16:20:11 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40399/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:12 INFO - PROCESS | 19958 | RSS: Main: 214888448 16:20:12 INFO - PROCESS | 19958 | 16:20:15 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40399/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:16 INFO - PROCESS | 19958 | RSS: Main: 214183936 16:20:16 INFO - PROCESS | 19958 | 16:20:19 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40399/tests/kraken/driver.html?testName=json-parse-financial) 16:20:19 INFO - PROCESS | 19958 | RSS: Main: 213987328 16:20:19 INFO - PROCESS | 19958 | 16:20:21 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40399/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:21 INFO - PROCESS | 19958 | RSS: Main: 175980544 16:20:21 INFO - PROCESS | 19958 | 16:20:23 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:24 INFO - PROCESS | 19958 | RSS: Main: 181989376 16:20:24 INFO - PROCESS | 19958 | 16:20:25 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:26 INFO - PROCESS | 19958 | RSS: Main: 188612608 16:20:26 INFO - PROCESS | 19958 | 16:20:29 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:29 INFO - PROCESS | 19958 | RSS: Main: 197128192 16:20:29 INFO - PROCESS | 19958 | 16:20:32 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:32 INFO - PROCESS | 19958 | RSS: Main: 194273280 16:20:32 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | Cycle 1(1): loaded http://localhost:40399/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40399/tests/kraken/driver.html?testName=ai-astar) 16:20:34 INFO - PROCESS | 19958 | RSS: Main: 245043200 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | __start_tp_report 16:20:34 INFO - PROCESS | 19958 | _x_x_mozilla_page_load 16:20:34 INFO - PROCESS | 19958 | _x_x_mozilla_page_load_details 16:20:34 INFO - PROCESS | 19958 | |i|pagename|runs| 16:20:34 INFO - PROCESS | 19958 | |0;ai-astar;112;109;124;111;110;112;111;112;110;111 16:20:34 INFO - PROCESS | 19958 | |1;audio-beat-detection;148;115;148;171;115;148;141;116;177;118 16:20:34 INFO - PROCESS | 19958 | |2;audio-dft;160;160;161;160;164;161;161;161;161;163 16:20:34 INFO - PROCESS | 19958 | |3;audio-fft;75;75;76;179;75;76;75;75;162;75 16:20:34 INFO - PROCESS | 19958 | |4;audio-oscillator;83;83;157;83;84;84;151;83;83;145 16:20:34 INFO - PROCESS | 19958 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 16:20:34 INFO - PROCESS | 19958 | |6;imaging-darkroom;145;146;145;145;145;145;146;146;146;145 16:20:34 INFO - PROCESS | 19958 | |7;imaging-desaturate;95;94;95;95;95;96;94;95;94;95 16:20:34 INFO - PROCESS | 19958 | |8;json-parse-financial;50;51;51;51;52;51;51;50;52;51 16:20:34 INFO - PROCESS | 19958 | |9;json-stringify-tinderbox;60;61;61;60;62;60;61;61;61;61 16:20:34 INFO - PROCESS | 19958 | |10;stanford-crypto-aes;73;74;73;74;76;73;78;73;73;73 16:20:34 INFO - PROCESS | 19958 | |11;stanford-crypto-ccm;122;128;118;120;127;117;128;119;159;122 16:20:34 INFO - PROCESS | 19958 | |12;stanford-crypto-pbkdf2;151;154;155;152;154;154;156;154;153;155 16:20:34 INFO - PROCESS | 19958 | |13;stanford-crypto-sha256-iterative;62;61;63;72;61;62;62;65;62;61 16:20:34 INFO - PROCESS | 19958 | __end_tp_report 16:20:34 INFO - PROCESS | 19958 | __start_cc_report 16:20:34 INFO - PROCESS | 19958 | _x_x_mozilla_cycle_collect,1673 16:20:34 INFO - PROCESS | 19958 | __end_cc_report 16:20:34 INFO - PROCESS | 19958 | __startTimestamp1477437634855__endTimestamp 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | ------- Summary: start ------- 16:20:34 INFO - PROCESS | 19958 | Number of tests: 14 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#0] ai-astar Cycles:10 Average:112.20 Median:111.50 stddev:4.26 (3.8%) stddev-sans-first:4.52 16:20:34 INFO - PROCESS | 19958 | Values: 112.0 109.0 124.0 111.0 110.0 112.0 111.0 112.0 110.0 111.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:148.00 stddev:23.17 (15.7%) stddev-sans-first:24.38 16:20:34 INFO - PROCESS | 19958 | Values: 148.0 115.0 148.0 171.0 115.0 148.0 141.0 116.0 177.0 118.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.32 (0.8%) stddev-sans-first:1.32 16:20:34 INFO - PROCESS | 19958 | Values: 160.0 160.0 161.0 160.0 164.0 161.0 161.0 161.0 161.0 163.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:40.36 (53.8%) stddev-sans-first:42.20 16:20:34 INFO - PROCESS | 19958 | Values: 75.0 75.0 76.0 179.0 75.0 76.0 75.0 75.0 162.0 75.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:83.00 stddev:32.83 (39.6%) stddev-sans-first:33.97 16:20:34 INFO - PROCESS | 19958 | Values: 83.0 83.0 157.0 83.0 84.0 84.0 151.0 83.0 83.0 145.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:20:34 INFO - PROCESS | 19958 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 16:20:34 INFO - PROCESS | 19958 | Values: 145.0 146.0 145.0 145.0 145.0 145.0 146.0 146.0 146.0 145.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:20:34 INFO - PROCESS | 19958 | Values: 95.0 94.0 95.0 95.0 95.0 96.0 94.0 95.0 94.0 95.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.60 16:20:34 INFO - PROCESS | 19958 | Values: 50.0 51.0 51.0 51.0 52.0 51.0 51.0 50.0 52.0 51.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 16:20:34 INFO - PROCESS | 19958 | Values: 60.0 61.0 61.0 60.0 62.0 60.0 61.0 61.0 61.0 61.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:73.50 stddev:1.70 (2.3%) stddev-sans-first:1.76 16:20:34 INFO - PROCESS | 19958 | Values: 73.0 74.0 73.0 74.0 76.0 73.0 78.0 73.0 73.0 73.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:124.50 stddev:12.29 (9.9%) stddev-sans-first:12.95 16:20:34 INFO - PROCESS | 19958 | Values: 122.0 128.0 118.0 120.0 127.0 117.0 128.0 119.0 159.0 122.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:154.00 stddev:1.48 (1.0%) stddev-sans-first:1.17 16:20:34 INFO - PROCESS | 19958 | Values: 151.0 154.0 155.0 152.0 154.0 154.0 156.0 154.0 153.0 155.0 16:20:34 INFO - PROCESS | 19958 | 16:20:34 INFO - PROCESS | 19958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.00 stddev:3.35 (5.4%) stddev-sans-first:3.53 16:20:34 INFO - PROCESS | 19958 | Values: 62.0 61.0 63.0 72.0 61.0 62.0 62.0 65.0 62.0 61.0 16:20:34 INFO - PROCESS | 19958 | -------- Summary: end -------- 16:20:34 INFO - PROCESS | 19958 | 16:20:35 INFO - TEST-INFO | 19958: exit 0 16:20:35 INFO - TEST-OK | kraken | took 59480ms 16:20:35 INFO - SUITE-END | took 1171s 16:20:36 INFO - Completed test suite (00:19:31) 16:20:36 INFO - javascript benchmark 16:20:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3204.0548038328693, 3216.9195770105744, 3248.2204270974958, 3271.2803183299666, 3290.457074582694, 5564.1931919871595, 5654.123610840326, 5675.5459563234945, 5682.687452190133, 5693.459121383472, 5703.116017017369, 5795.739191736371, 5797.594168408203, 5808.796692115777, 5814.389489103636, 5241.266222728821, 5242.633015688884, 5249.86875328117, 5311.654742441749, 5316.6544174628525, 4986.725730084844, 5088.389393272794, 5330.067238233313, 5331.600129990247, 5336.039512887679, 5035.144025515671, 5122.667026643257, 5131.102057140004, 5140.383154021522, 5165.870853228662, 5150.150225212854, 5167.173252279655, 5176.741162941857, 5179.7928082877015, 5211.5830733541225, 4926.064047830937, 4971.801127954877, 5046.44489106198, 5069.949300507022, 5135.64050516464, 5494.230807686927, 5554.694491802949, 5579.832605021816, 5583.972080139573, 5623.915641265385, 6000.939990600082, 6035.396460353973, 6047.516198704137, 6048.516118710492, 6049.758009679607, 5817.185594016841, 5852.795152169653, 6120.387961203888, 6126.540509461808, 6131.969340153315, 5566.666000040011, 5652.463016013472, 5653.23681303027, 5655.717214139277, 5693.77224911003, 6183.783567575113, 6186.628802271878, 6192.752289908443, 6195.442410183015, 6198.876022479525, 5709.714514274353, 5940.910886336708, 5947.1079338099635, 5947.9702601486715, 5966.045432730829, 6044.172011125112, 6178.4439400453275, 6184.907226391608, 6194.039923811816, 6194.9690251549355, 6140.478059365004, 6174.351693072163, 6176.042713379434, 6180.103884936749, 6184.196054512884, 5921.111833224964, 6003.879922401615, 6186.133941248228, 6188.195534580565, 6191.597546159485, 4625.5836974671765, 4631.421072365954, 4667.696599721007, 4678.0, 4736.384270044872, 4152.647025002909, 4157.168566286694, 4159.521655009652, 4160.18876319121, 4177.039280965395, 5881.588288819741, 5896.705164741831, 5900.468957793819, 5922.733476993546, 5924.526037917042, 5981.37195594465, 6060.969695151584, 6121.418465245794, 6123.877522449526, 6164.938350616526, 5824.388439213906, 5867.354590994987, 5868.0, 5870.941290587125, 5896.941030589639, 5322.121849894801, 5325.600579956519, 5327.147656374961, 5327.653702509325, 5333.919991200135, 5906.5570082244, 6179.227596550431, 6252.624842509373, 6271.96864015677, 6391.20109986261, 6166.537509686702, 6169.96915015422, 6179.165812615419, 6186.566940314315, 6191.721372538156, 6365.840853978688, 6374.776882809079, 6376.649284289409, 6376.776812811529, 6381.266154392304, 5607.186957891165, 5847.619904706181, 5868.94131058701, 5891.970540147272, 6000.909986350295, 6020.909686354796, 6064.4541991221, 6069.2716873975405, 6074.271087469532, 6075.483583895419, 5857.326407463196, 5910.970445147746, 5914.378990205966, 5921.171036055042, 5921.851953701192, 5605.075162598126, 5766.769329226784, 5837.328707198726, 5845.824625261249, 5959.642421454727, 5841.065429531254, 5841.94158058414, 5852.4440178183, 5854.092615644582, 5856.677882716321, 5923.703814809156, 5932.525397968238, 5942.108683697307, 5946.762129514944, 5949.226600542075, 5609.467100625434, 5851.795187168429, 5858.970705146447, 5880.323762767336, 5906.586538942232], "value": 5600.549084948062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.9005669036263, 630.4074170279937, 641.1344684675688, 650.1418128070945, 657.3065415986133, 34807.0, 37758.0, 44600.553994460046, 45420.0, 45492.54507454924, 44709.0, 45383.54616453835, 45405.0, 45450.0, 45457.0, 9148.58831352589, 9302.348835581512, 9305.860412093823, 9311.860322095174, 9313.95343023284, 8444.071152173256, 10729.570817167303, 10979.83530247047, 11029.669109926715, 11344.886551134507, 11190.272632278917, 11195.776084478306, 11253.718657033598, 11298.604548840793, 11301.717457063556, 10782.73043173919, 10853.294535855222, 10859.511321990529, 10862.45687715619, 10864.674059778219, 10925.399103049329, 10970.067544258747, 10987.39569323687, 10989.835152472719, 11002.889971100267, 44208.778956105336, 44365.77817110926, 44376.0, 44552.554474455486, 44711.552884471064, 3739.4577786221116, 3760.811959402019, 3811.4092315691114, 3990.5610382857863, 4438.800253988578, 34511.0, 34602.30795384103, 34668.0, 34874.82562587196, 35275.76534821269, 4652.74409907455, 4722.1028004679, 4768.308595253703, 4773.618110551148, 4847.684900481458, 353816.0, 353964.0, 354279.0, 354794.0, 354951.0, 59026.0, 59114.70442647759, 59667.0, 59728.70135649294, 59777.0, 38811.41782873259, 39670.80164599159, 39738.80130599386, 41870.1625967485, 62503.37496624976, 14205.147691138565, 14362.0, 14374.281285935662, 14375.28123593837, 14397.568072957618, 10236.33463824849, 10444.164466842634, 10550.63072792464, 10593.947030264953, 10604.575816967235, 19587.510312242022, 20297.89851050735, 20311.4922126948, 20497.0, 20550.486237843874, 7390.852182956311, 7557.886631700529, 7567.977943380035, 7592.202818704067, 7683.807904802425, 980.612658000086, 1088.983665245022, 1090.5092708281304, 1152.4180288954024, 1189.9524019039316, 32524.0, 33075.83462082674, 33710.0, 33864.0, 34119.31761364759, 1104.2160066352978, 1118.5134466507095, 1147.988520114805, 1152.4122697424375, 1247.2267194339568, 16390.67218655644, 17406.47780566585, 17506.387276445264, 17514.211860466054, 17651.0, 596199.0, 605249.0, 608578.0, 608971.0, 609359.0, 62053.37946620566, 62628.68685656542, 62829.05756413657, 62854.68572657199, 62877.0, 1312.1536608887213, 1380.3098450774612, 1418.44680574576, 1430.9212993285678, 1437.6837095838898, 33460.83269583636, 34505.48241776426, 35481.46777798387, 35551.82224088863, 35570.64429355673, 53388.73305633447, 53863.0, 54127.72936135294, 54178.0, 54179.187312191134, 440852.0, 445841.0, 446113.0, 446644.0, 449230.0, 27309.726902731512, 29647.851760741058, 30627.54058689077, 30659.846700766353, 32209.0, 49684.75157624333, 50371.0, 50502.747486262335, 50716.0, 50829.49170508396, 42219.0, 42510.1497970045, 43484.130317394105, 43500.12999740051, 44984.77507612572, 44082.77958610187, 44091.77954110209, 44217.77891110653, 45219.321710175034, 48392.758036211, 43333.133337332445, 43984.34023489586, 44303.0, 44446.55553444424, 44463.0, 36888.0, 38717.419238712006, 39152.0, 39824.80087599543, 39875.60124398835, 28949.276268093465, 29005.709942901143, 29562.260943476584, 29591.556126658546, 29603.259918502208], "value": 21107.597425867505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.49102349621717, 422.5056683680094, 431.50592571505615, 432.2414163143683, 435.91935491933975, 18970.336038238664, 19571.706424403626, 20337.898310508426, 20543.486412839688, 20609.0, 19564.510887227792, 19693.50766230845, 19851.900740496276, 20387.490312742153, 20486.0, 5765.942340576593, 5968.970155149218, 5975.25309336333, 5980.342162362137, 5984.401559844003, 5905.793297234576, 5915.260592425947, 5934.0, 5969.104634304846, 5974.611650242742, 5795.507381872546, 5925.614835035731, 5936.5250779937505, 5944.167816505671, 5951.464368206861, 5857.062869940811, 5874.089516125007, 5895.941040589582, 5911.408859114097, 5938.198343223674, 5344.759485823147, 5345.385280692732, 5348.5186333230195, 5350.197470379435, 5354.892902141935, 23253.651195232083, 24503.142390016263, 24530.754692453025, 24731.876340618182, 24851.0, 2606.1660268714018, 2624.448865738197, 2817.1970988268245, 2817.8450185239803, 2973.4499117663086, 14813.55559333211, 15089.170095644846, 15095.320710567941, 15480.922595387063, 15529.0, 1802.774653168354, 1998.7001949707515, 2031.1489277006547, 2093.884836334001, 2108.5572029873747, 64952.675236623516, 66657.66671166613, 78163.0, 86916.5654171725, 100520.49739751255, 17837.28650853977, 18271.26914923388, 18686.532836678918, 18982.620347592972, 19277.036148192536, 33401.0, 33582.16044598856, 33717.0, 33911.49132763011, 34386.48420273698, 9476.386447179204, 9532.285078619132, 9577.377470464398, 9679.177269932135, 10242.334248273988, 7075.327843854826, 7624.42816788743, 7821.0, 7825.178356272623, 7960.761177164694, 9296.814063718823, 9400.0, 9462.57418416159, 9502.524873756285, 9613.230941524662, 3549.2723991581665, 3585.229175727179, 3691.187938653492, 3796.6583007529457, 3951.3677811550015, 819.8565251081156, 938.9108034736689, 985.049427302642, 1037.3464717228098, 1079.9082078023457, 19754.506137346394, 19810.0, 20161.39515814528, 20298.18807247723, 20848.687269690967, 1007.496251874063, 1016.3850870223408, 1017.4251547875426, 1054.4306074719566, 1090.0571006079683, 10803.297785644065, 11358.636963564426, 11441.885581144248, 11445.656630300939, 11511.424428778528, 547109.0, 547521.0, 556547.0, 558060.0, 558272.0, 94603.52698236465, 97117.0, 99596.50201748945, 99711.50144249233, 102427.0, 969.3117886300805, 1004.9598016079422, 1021.1065317846735, 1034.5137785240925, 1034.9689509314733, 17550.473485795446, 18066.367677131762, 18081.457556273333, 18499.630007399508, 18510.629787404447, 43412.348814767174, 44134.55865441304, 45020.0, 45747.0, 46170.307445389015, 61515.692421537606, 62297.75404491794, 63042.68478657577, 63552.36447635465, 63897.36102638914, 9009.144131307516, 9290.674826381315, 9415.10556497159, 9504.619815207314, 9528.761780955254, 12722.872771272168, 13314.733705326033, 13860.792088118887, 13953.302334883012, 14029.438822446988, 11355.602553910574, 11484.770304594029, 11599.130065244972, 11646.184767066225, 12028.518859245532, 11704.356260405419, 12100.45547950362, 12248.203866748632, 12279.938600306941, 12607.49570017189, 11752.471138798946, 11797.646070617895, 11841.940790295994, 11860.644180674593, 11865.110116741107, 9352.579133938852, 9368.672096476863, 9375.906240937504, 9450.621975120917, 9477.857832132386, 11426.885731142582, 11595.768084638428, 11725.0, 11815.940920295343, 12162.0], "value": 10435.854106037994, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.4939297156538, 1478.4160256698603, 1588.610790356363, 1776.573622330641, 1811.4475085099039, 8939.508327042011, 9525.714228573137, 9537.093976072261, 9549.809003819919, 9551.61793528259, 7972.601369931509, 8965.641374345018, 9178.449293042422, 9233.538323083852, 9325.81348373032, 2514.7736703696664, 2561.8206725529126, 2565.8973641054336, 2566.7304932982047, 2572.65269188659, 2521.621756736486, 2566.1788227767124, 2567.396661784479, 2567.9101231456902, 2571.472848066142, 2472.11004038546, 2492.401823562341, 2565.4484285878534, 2565.525377805112, 2566.396896729267, 2505.6742623458927, 2518.106072344319, 2518.4837108392826, 2545.376382786211, 2550.0437335999, 2516.094206085808, 2517.647529345893, 2526.5199612073648, 2528.102523604131, 2547.6305935639416, 9342.67300644481, 9577.808443831154, 9580.377275477073, 9590.42457452555, 9591.328606997513, 5762.827115186509, 5924.763009479616, 5929.436703513203, 5935.228420305332, 5936.228290322274, 3619.402798538238, 3774.5281839770028, 3788.583255841883, 3793.3551296279425, 3832.061145019488, 1200.639808057588, 1244.402686710375, 1249.1256120715445, 1252.167308739687, 1277.8083287506856, 13177.934110329386, 13262.53581124656, 13308.0, 13365.599032029055, 13396.799048014385, 5097.082525145435, 5102.719350435687, 5109.0, 5149.124648809711, 5311.840644780663, 11344.829827552594, 11377.943110284396, 11390.772184556263, 11394.94302528482, 11403.087752979742, 5878.382769809108, 5892.793752218738, 5918.230630018156, 5919.378465261172, 5922.733476993546, 4652.860414187579, 5239.711815850165, 5332.093544097513, 5382.838514844561, 5442.836714898559, 4399.802008909607, 4459.643228541774, 4505.6846020778885, 4546.727196368229, 4678.345031695565, 1393.9512117075853, 1452.4045141491938, 1455.2287287737515, 1470.4191844221473, 1477.8448262932238, 884.6196135661598, 940.9012053734259, 1002.9548670309854, 1003.2325271167563, 1008.3798463944621, 6068.423499767515, 6181.0, 6303.275123360872, 6304.590201636879, 6396.456301214449, 484.29051439641097, 491.53058828818394, 498.7755510020505, 501.02800566900095, 504.19580768673904, 4215.199112168678, 4278.315469524923, 4281.743095414286, 4286.65706743466, 4295.183915056129, 11996.220245684002, 12493.812592811115, 12523.123381363399, 12529.561465348852, 12562.183458075378, 12446.25322480654, 12464.376781160905, 12481.063920206021, 12493.687657808627, 12504.874951250371, 8703.738887833375, 9049.864252036225, 9051.637934482547, 9222.0, 9229.307801914883, 6032.607880487754, 6166.537509686702, 6230.190075290183, 6280.120783090462, 6383.457406120346, 11196.888031119584, 11283.605073822706, 11472.0, 11700.0, 11779.0, 11102.222844400814, 11341.489632966372, 11546.422678865854, 11744.060475161796, 11865.5847045353, 7652.885206722015, 8224.671013159406, 8413.70552030676, 8514.191151840565, 8569.10024447449, 9339.112784285482, 9426.245900328095, 9446.763830904283, 9455.0, 9460.621575136918, 8379.790505237417, 8479.024912134937, 8507.149285071691, 8590.1839325264, 8733.65065397377, 7979.880301795594, 8166.42835001568, 8224.103063440845, 8294.626741796754, 8495.74512764618, 7098.503104782616, 7142.393351081082, 7154.463415243774, 7159.284071592799, 7217.170025447141, 4962.181240095489, 4991.675541089818, 5036.169032109808, 5052.115879721107, 5085.211792172219, 5172.224166375074, 5261.0, 5352.812651557177, 5397.298351214317, 5410.431904649955], "value": 4937.2997556857235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3348537550591, 354.4895350694999, 357.0912028886484, 359.3513707757497, 364.3896473407044, 5509.256250406194, 5556.277683901092, 5578.972105139469, 5583.911137328223, 5590.748416321265, 5157.355330583677, 5301.496357846008, 5308.973455132719, 5312.867178320544, 5492.395836457997, 4252.808623611945, 4318.460192475941, 4367.737935723851, 4408.5591440855815, 4409.001878722467, 4345.500267469234, 4348.369486424466, 4350.195213885426, 4362.432883725127, 4405.82376704933, 4157.355609880458, 4291.549387314319, 4305.892352691192, 4318.740875547462, 4379.802908869108, 4285.44289242398, 4288.764117973495, 4313.568643135662, 4317.0, 4330.7401555906745, 3817.1220619257724, 3853.2100919311756, 3859.826307816155, 3861.0694652673665, 3863.8261278242267, 5218.895622087575, 5319.9734001330135, 5393.460653934614, 5405.972970135124, 5417.349918009824, 4942.9258561121615, 4974.801007959677, 4987.700737955698, 4991.101601711691, 5004.099262132815, 5688.829335119953, 5695.971520142372, 5720.1991721159075, 5750.367459579443, 5779.739911703983, 4115.300398932189, 4169.97915010423, 4194.412782210493, 4207.705460617758, 4224.978875105636, 5861.20873682056, 5909.699866029467, 5921.111833225008, 5948.018576934801, 5952.613080149819, 4288.978555107205, 4293.313069908862, 4326.24290749124, 4335.154644844288, 4336.956630433719, 4275.144971005749, 4336.522982471925, 4368.257396242647, 4378.956210437855, 4380.211561918822, 4895.6083513318845, 4905.484924082992, 4908.386451693538, 4920.3603531541385, 4922.926156107661, 5776.624519406225, 5787.466321424826, 5792.725600367912, 5800.854978625568, 5846.649201047866, 1399.9290542734611, 1447.4572035486692, 1460.7662773956115, 1485.843986381436, 1507.2991059157544, 1019.1235537437798, 1021.1728499914944, 1041.401194313273, 1046.6598355534481, 1048.496721573649, 5545.611807173459, 5560.777568897199, 5605.421506107236, 5621.831345059655, 5625.0, 5812.447817457335, 5816.905913871003, 5821.446962538552, 5874.676892770939, 5880.206172166788, 5399.8687324727925, 5527.502524772709, 5541.168824676331, 5552.639078459887, 5571.442855714477, 2731.7677997370447, 2751.834889906612, 2763.231531819637, 2765.5575107983027, 2781.7635500982237, 5272.151329529388, 5286.779552587922, 5305.681659100466, 5348.545373643206, 5406.648567843156, 4912.9508704913205, 5130.4356520782685, 5183.663061900965, 5197.84406467799, 5224.425313215544, 5232.476752324737, 5280.445553216934, 5280.9471905281225, 5302.92045619316, 5331.027519817615, 5125.487451254845, 5144.228365745168, 5150.845474635767, 5164.690118592896, 5241.554467870274, 4860.562549370574, 5085.211792172219, 5091.592672586258, 5097.490250974873, 5130.461301563282, 5262.447443018428, 5313.229581710553, 5340.439253878442, 5350.705711185922, 5370.62405631602, 3851.9792255051875, 3859.3824988001784, 3894.812082314808, 3903.765774053566, 3923.862664806718, 4842.394700662418, 5127.974360128175, 5140.948590514047, 5153.819616313411, 5175.948240517697, 5526.005319042611, 5658.660480371191, 5709.885802283848, 5767.769289228551, 5768.307803063659, 5750.25246717924, 5760.0, 5760.473474529337, 5761.740721667451, 5785.0, 5618.634788738719, 5659.886802264014, 5663.423142470084, 5712.942870571407, 5720.227769251098, 5736.913946290725, 5773.682447465262, 5782.508486778671, 5796.507296879644, 5796.652200867961, 5626.437356264504, 5654.180143879197, 5656.745446454837, 5696.487316141567, 5743.0811070228565], "value": 4276.750556086178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.1650766694972, 816.4978538199009, 818.0960039156731, 822.8806823010661, 828.7555171224489, 16252.024878507284, 16672.74990875135, 16858.49424517265, 16865.66268674626, 17683.64632707345, 16251.593710157222, 16380.83619163808, 16390.508284751446, 16391.180440977932, 16410.425635102773, 717.6734585763465, 836.6277006732007, 851.1998721202082, 872.1845074854999, 877.4515927545284, 857.5412154497315, 863.9179277968583, 865.9263962563158, 868.1231955724701, 869.491347561674, 913.9771505712343, 915.2860768600469, 915.6703586708779, 917.7659696777346, 921.3274309753883, 910.4855756497558, 913.5340976102202, 915.6108653822132, 916.9546716742918, 917.0279503726039, 667.6361383046251, 669.9430548403343, 672.7914346552584, 673.2056173715014, 674.1943377663694, 16841.494755157364, 16919.830801691947, 17077.060761658104, 17138.31446742129, 17166.742498862528, 15991.440299589574, 16301.429449969313, 16663.25015374311, 16722.916385417993, 16904.0, 13151.0, 13296.867031329659, 13372.531961381304, 13453.66365840852, 13484.190948543119, 4029.6977726670325, 4041.1109555897656, 4116.732412393185, 4121.0, 4248.830046798124, 81598.59200704018, 82042.0, 82604.17395826086, 83856.58071709663, 84750.0, 11661.24201926889, 12016.699582510333, 12137.817932731015, 12338.938305308417, 12472.43874025671, 18925.24299028051, 18958.52603684919, 18978.14598343078, 18978.62042759137, 18998.620027599372, 8683.131686831266, 8689.0, 8848.867266991001, 8969.416987895765, 9087.81824363509, 5145.331106956071, 5155.510226528474, 5165.1219292720325, 5241.24002019705, 5449.128139497661, 3903.590123037097, 4045.7167998239843, 4059.127287633173, 4061.289274376972, 4063.045184381668, 659.035726673602, 669.1535207961932, 672.9495287853431, 676.036647776916, 679.9626020568817, 641.7208514296296, 656.448583190113, 754.4680999895061, 789.4710543935577, 808.720991258015, 9638.518074096268, 10487.790244195074, 10523.421211833424, 10950.34297942126, 10975.012248897478, 626.674129452682, 732.7948174511146, 751.6467260387609, 772.4708574626399, 780.42248735935, 5057.645964782503, 5225.005597154203, 5369.114096174166, 5390.380106287748, 5444.537214336747, 77135.61432192916, 106655.9334406646, 131921.0, 135588.0, 138666.30666846802, 68570.65714671394, 78609.0, 113515.0, 114620.0, 118068.0, 60276.39723602796, 63815.680921596024, 88453.55773221221, 96817.51591241999, 99386.0, 10069.043440873105, 10086.646967356108, 10367.84448233262, 10447.634332798316, 10516.842247366143, 57581.71209143927, 57866.7106664464, 59186.0, 60499.0, 60866.0, 77006.0, 83856.0, 87174.56412717896, 88040.0, 89755.55122224348, 12138.696532586757, 12510.0, 12841.101122921305, 14073.296335182995, 14170.149791012573, 15574.299156538209, 16359.754603680716, 17222.224999874787, 17246.137693115546, 17691.0, 15465.458708945584, 15811.683766324839, 16405.179741012664, 16679.166041697623, 16924.830751692327, 14452.494162704255, 14665.340059697126, 14773.852261477678, 14837.10977341363, 14991.100533967996, 13490.662733431744, 13870.098443601133, 14747.483838065617, 14861.182634955181, 15073.472428464951, 11684.937834978304, 12061.939690301844, 12673.556425525063, 12782.68043298925, 12989.155704879153, 12272.140950133762, 12272.877271227531, 12300.50797968107, 12361.876381236072, 12733.29966851832], "value": 8133.466135488654, "unit": "score"}], "name": "dromaeo_css", "value": 7721.23800297251, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 124.0, 111.0, 110.0, 112.0, 111.0, 112.0, 110.0, 111.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 148.0, 171.0, 115.0, 148.0, 141.0, 116.0, 177.0, 118.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 160.0, 164.0, 161.0, 161.0, 161.0, 161.0, 163.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 179.0, 75.0, 76.0, 75.0, 75.0, 162.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 157.0, 83.0, 84.0, 84.0, 151.0, 83.0, 83.0, 145.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 51.0, 52.0, 51.0, 51.0, 50.0, 52.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 60.0, 62.0, 60.0, 61.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 74.0, 76.0, 73.0, 78.0, 73.0, 73.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 128.0, 118.0, 120.0, 127.0, 117.0, 128.0, 119.0, 159.0, 122.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 154.0, 155.0, 152.0, 154.0, 154.0, 156.0, 154.0, 153.0, 155.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 72.0, 61.0, 62.0, 62.0, 65.0, 62.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1496.7999999999997, "alertThreshold": 2.0}]} 16:20:36 INFO - Return code: 0 16:20:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:20:36 INFO - # TBPL SUCCESS # 16:20:36 INFO - Running post-action listener: _resource_record_post_action 16:20:36 INFO - [mozharness: 2016-10-25 23:20:36.193383Z] Finished run-tests step (success) 16:20:36 INFO - Running post-run listener: _resource_record_post_run 16:20:36 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291057664; Read time: 4; Write time: 196128 16:20:36 INFO - TinderboxPrint: CPU usage
13.0% 16:20:36 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 16:20:36 INFO - TinderboxPrint: I/O write bytes / time
291,057,664 / 196,128 16:20:36 INFO - TinderboxPrint: CPU idle
8,239.8 (87.0%) 16:20:36 INFO - TinderboxPrint: CPU user
1,184.6 (12.5%) 16:20:36 INFO - TinderboxPrint: Swap in / out
0 / 0 16:20:36 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71340032; Read time: 0; Write time: 41056 16:20:36 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 219717632; Read time: 0; Write time: 155072 16:20:36 INFO - Running post-run listener: _upload_blobber_files 16:20:36 INFO - Blob upload gear active. 16:20:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:20:36 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 16:20:36 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'] 16:20:36 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 16:20:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:20:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:20:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:41 INFO - (blobuploader) - INFO - Done attempting. 16:20:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:20:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:43 INFO - (blobuploader) - INFO - Done attempting. 16:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:20:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:44 INFO - (blobuploader) - INFO - Done attempting. 16:20:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:20:44 INFO - Return code: 0 16:20:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:20:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:20:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ccc1edc19465c88afca46102a7d0574f84debd1c7e59525e00628cf5f52ffe1b517ddfd7e9ecbd9c605acc9baf4849b60ed3c009b1621e58b600ccbb3c156798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d788c1ae2aba7d34207b94cb8812deb8010383b300ee081726a3374586db997a68ea518b293686650b51338bacbf75222b6c30758025e1233e823de08caf8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ad82c1e6e3fd3e8a5cb29f0ee42b3ee91dd2738676f8c304af6bf7a8a9fecfc26e0d1fa30c01be7f13e429f523bce1a6ad5ab1aa97bd6707e15672fe0e9cd3"} 16:20:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:20:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:20:44 INFO - Contents: 16:20:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ccc1edc19465c88afca46102a7d0574f84debd1c7e59525e00628cf5f52ffe1b517ddfd7e9ecbd9c605acc9baf4849b60ed3c009b1621e58b600ccbb3c156798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d788c1ae2aba7d34207b94cb8812deb8010383b300ee081726a3374586db997a68ea518b293686650b51338bacbf75222b6c30758025e1233e823de08caf8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ad82c1e6e3fd3e8a5cb29f0ee42b3ee91dd2738676f8c304af6bf7a8a9fecfc26e0d1fa30c01be7f13e429f523bce1a6ad5ab1aa97bd6707e15672fe0e9cd3"} 16:20:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:20:44 INFO - Copying logs to upload dir... 16:20:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:20:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.138498 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-10-25 16:20:43.639173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:20:43.642951) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 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/ccc1edc19465c88afca46102a7d0574f84debd1c7e59525e00628cf5f52ffe1b517ddfd7e9ecbd9c605acc9baf4849b60ed3c009b1621e58b600ccbb3c156798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d788c1ae2aba7d34207b94cb8812deb8010383b300ee081726a3374586db997a68ea518b293686650b51338bacbf75222b6c30758025e1233e823de08caf8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ad82c1e6e3fd3e8a5cb29f0ee42b3ee91dd2738676f8c304af6bf7a8a9fecfc26e0d1fa30c01be7f13e429f523bce1a6ad5ab1aa97bd6707e15672fe0e9cd3"} build_url:https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/RqfhivTYScmC1RKsOyMDkQ/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/ccc1edc19465c88afca46102a7d0574f84debd1c7e59525e00628cf5f52ffe1b517ddfd7e9ecbd9c605acc9baf4849b60ed3c009b1621e58b600ccbb3c156798", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0d788c1ae2aba7d34207b94cb8812deb8010383b300ee081726a3374586db997a68ea518b293686650b51338bacbf75222b6c30758025e1233e823de08caf8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ad82c1e6e3fd3e8a5cb29f0ee42b3ee91dd2738676f8c304af6bf7a8a9fecfc26e0d1fa30c01be7f13e429f523bce1a6ad5ab1aa97bd6707e15672fe0e9cd3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-25 16:20:43.668424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:20:43.668762) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1477435965.24406-561241642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-25 16:20:43.753345) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:20:43.753665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-25 16:20:43.754066) ========= ========= Total master_lag: 0.18 =========