builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1477359263.47 results: success (0) buildid: 20161024155222 builduid: 15fbbaa9ded64c018a3e00caeea64cec revision: 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.471301) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.471843) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.472194) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.487211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.487582) ========= 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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 18:34:23-- 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% 51.0M=0s 2016-10-24 18:34:23 (51.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120922 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.622135) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.622460) ========= 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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014587 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:23.672163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-24 18:34:23.672525) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 --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 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 --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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 18:34:23,976 truncating revision to first 12 chars 2016-10-24 18:34:23,977 Setting DEBUG logging. 2016-10-24 18:34:23,977 attempt 1/10 2016-10-24 18:34:23,977 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4131f9c0b0c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 18:34:24,345 unpacking tar archive at: mozilla-beta-4131f9c0b0c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.800536 ========= master_lag: 0.50 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-24 18:34:24.974542) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:24.974861) ========= script_repo_revision: 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:24.975247) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:24.975539) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 18:34:24.989722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-24 18:34:24.990102) ========= /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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:34:25 INFO - MultiFileLogger online at 20161024 18:34:25 in /builds/slave/test 18:34:25 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 18:34:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:34:25 INFO - {'append_to_log': False, 18:34:25 INFO - 'base_work_dir': '/builds/slave/test', 18:34:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:34:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:34:25 INFO - 'branch': 'Mozilla-Beta', 18:34:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:34:25 INFO - 'config_files': ('talos/linux_config.py',), 18:34:25 INFO - 'default_actions': ('clobber', 18:34:25 INFO - 'read-buildbot-config', 18:34:25 INFO - 'download-and-extract', 18:34:25 INFO - 'populate-webroot', 18:34:25 INFO - 'create-virtualenv', 18:34:25 INFO - 'install', 18:34:25 INFO - 'run-tests'), 18:34:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:34:25 INFO - 'download_minidump_stackwalk': True, 18:34:25 INFO - 'download_symbols': 'ondemand', 18:34:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:34:25 INFO - 'tooltool.py': '/tools/tooltool.py', 18:34:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:34:25 INFO - '/tools/misc-python/virtualenv.py')}, 18:34:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:34:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:34:25 INFO - 'installer_path': 'installer.exe', 18:34:25 INFO - 'log_level': 'info', 18:34:25 INFO - 'log_name': 'talos', 18:34:25 INFO - 'log_to_console': True, 18:34:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:34:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:34:25 INFO - 'opt_config_files': (), 18:34:25 INFO - 'pip_index': False, 18:34:25 INFO - 'sps_profile': False, 18:34:25 INFO - 'sps_profile_interval': 0, 18:34:25 INFO - 'suite': 'dromaeojs', 18:34:25 INFO - 'system_bits': '32', 18:34:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:34:25 INFO - 'title': 'talos-linux64-ix-049', 18:34:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:34:25 INFO - 'use_talos_json': True, 18:34:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:34:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:34:25 INFO - 'work_dir': 'build'} 18:34:25 INFO - [mozharness: 2016-10-25 01:34:25.376419Z] Running clobber step. 18:34:25 INFO - Running pre-action listener: _resource_record_pre_action 18:34:25 INFO - Running main action method: clobber 18:34:25 INFO - rmtree: /builds/slave/test/build 18:34:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:34:25 INFO - Running post-action listener: _resource_record_post_action 18:34:25 INFO - [mozharness: 2016-10-25 01:34:25.842078Z] Finished clobber step (success) 18:34:25 INFO - [mozharness: 2016-10-25 01:34:25.842180Z] Running read-buildbot-config step. 18:34:25 INFO - Running pre-action listener: _resource_record_pre_action 18:34:25 INFO - Running main action method: read_buildbot_config 18:34:25 INFO - Using buildbot properties: 18:34:25 INFO - { 18:34:25 INFO - "project": "", 18:34:25 INFO - "product": "firefox", 18:34:25 INFO - "script_repo_revision": "production", 18:34:25 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:34:25 INFO - "repository": "", 18:34:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:34:25 INFO - "buildid": "20161024155222", 18:34:25 INFO - "pgo_build": "True", 18:34:25 INFO - "basedir": "/builds/slave/test", 18:34:25 INFO - "buildnumber": 439, 18:34:25 INFO - "slavename": "talos-linux64-ix-049", 18:34:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:34:25 INFO - "platform": "ubuntu64_hw", 18:34:25 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:34:25 INFO - "branch": "mozilla-beta", 18:34:25 INFO - "repo_path": "releases/mozilla-beta", 18:34:25 INFO - "revision": "4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60", 18:34:25 INFO - "stage_platform": "linux64-pgo", 18:34:25 INFO - "builduid": "15fbbaa9ded64c018a3e00caeea64cec", 18:34:25 INFO - "slavebuilddir": "test" 18:34:25 INFO - } 18:34:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 18:34:25 INFO - Running post-action listener: _resource_record_post_action 18:34:25 INFO - [mozharness: 2016-10-25 01:34:25.844650Z] Finished read-buildbot-config step (success) 18:34:25 INFO - [mozharness: 2016-10-25 01:34:25.844740Z] Running download-and-extract step. 18:34:25 INFO - Running pre-action listener: _resource_record_pre_action 18:34:25 INFO - Running main action method: download_and_extract 18:34:25 INFO - mkdir: /builds/slave/test/build/tests 18:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:34:25 INFO - https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:34:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:34:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:34:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:34:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:34:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:34:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:34:25 INFO - retry: Failed, sleeping 30 seconds before retrying 18:34:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:34:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:34:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:34:55 INFO - retry: Failed, sleeping 60 seconds before retrying 18:35:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:35:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:35:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:35:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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! 18:35:55 INFO - Caught exception: HTTP Error 404: Not Found 18:35:55 INFO - Caught exception: HTTP Error 404: Not Found 18:35:55 INFO - Caught exception: HTTP Error 404: Not Found 18:35:55 INFO - trying https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:35:55 INFO - Downloading https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:35:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:35:56 INFO - Downloaded 1327 bytes. 18:35:56 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 18:35:56 INFO - Using the following test package requirements: 18:35:56 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:35:56 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:35:56 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 18:35:56 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'jsshell-linux-x86_64.zip'], 18:35:56 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:35:56 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 18:35:56 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 18:35:56 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 18:35:56 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:35:56 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 18:35:56 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:35:56 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:35:56 INFO - https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:35:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:35:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:35:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:35:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:35:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:35:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:35:56 INFO - retry: Failed, sleeping 30 seconds before retrying 18:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:36:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:36:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:36:26 INFO - retry: Failed, sleeping 60 seconds before retrying 18:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:37:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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! 18:37:26 INFO - Caught exception: HTTP Error 404: Not Found 18:37:26 INFO - Caught exception: HTTP Error 404: Not Found 18:37:26 INFO - Caught exception: HTTP Error 404: Not Found 18:37:26 INFO - trying https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:26 INFO - Downloading https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:27 INFO - Downloaded 21775790 bytes. 18:37:27 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'] 18:37:27 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 18:37:28 INFO - Return code: 0 18:37:28 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 18:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:28 INFO - https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:37:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:37:28 INFO - retry: Failed, sleeping 30 seconds before retrying 18:37:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:37:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:37:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:37:58 INFO - retry: Failed, sleeping 60 seconds before retrying 18:38:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:38:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:38:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:38:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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! 18:38:58 INFO - Caught exception: HTTP Error 404: Not Found 18:38:58 INFO - Caught exception: HTTP Error 404: Not Found 18:38:58 INFO - Caught exception: HTTP Error 404: Not Found 18:38:58 INFO - trying https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 18:38:58 INFO - Downloading https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:38:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:38:59 INFO - Downloaded 21775790 bytes. 18:38:59 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'] 18:38:59 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 18:39:00 INFO - Return code: 0 18:39:00 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')]} 18:39:00 INFO - https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:39:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:39:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:39:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:39:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:39:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:39:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:39:00 INFO - retry: Failed, sleeping 30 seconds before retrying 18:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:39:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:39:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:39:31 INFO - retry: Failed, sleeping 60 seconds before retrying 18:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:40:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:40:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:40:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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! 18:40:31 INFO - Caught exception: HTTP Error 404: Not Found 18:40:31 INFO - Caught exception: HTTP Error 404: Not Found 18:40:31 INFO - Caught exception: HTTP Error 404: Not Found 18:40:31 INFO - trying https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 18:40:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:40:31 INFO - Downloaded 14413762 bytes. 18:40:31 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'] 18:40:31 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 18:40:32 INFO - Return code: 0 18:40:32 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')]} 18:40:32 INFO - https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:40:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:40:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:40:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:40:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:40:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 18:40:32 INFO - retry: Failed, sleeping 30 seconds before retrying 18:41:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:41:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:41:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 18:41:02 INFO - retry: Failed, sleeping 60 seconds before retrying 18:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:42:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:42:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 18:42:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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! 18:42:02 INFO - Caught exception: HTTP Error 404: Not Found 18:42:02 INFO - Caught exception: HTTP Error 404: Not Found 18:42:02 INFO - Caught exception: HTTP Error 404: Not Found 18:42:02 INFO - trying https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:42:02 INFO - Downloading https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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 18:42:03 INFO - Downloaded 56803060 bytes. 18:42:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:42:03 INFO - mkdir: /builds/slave/test/properties 18:42:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:42:03 INFO - Writing to file /builds/slave/test/properties/build_url 18:42:03 INFO - Contents: 18:42:03 INFO - build_url:https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 18:42:04 INFO - Running post-action listener: _resource_record_post_action 18:42:04 INFO - Running post-action listener: set_extra_try_arguments 18:42:04 INFO - [mozharness: 2016-10-25 01:42:04.111719Z] Finished download-and-extract step (success) 18:42:04 INFO - [mozharness: 2016-10-25 01:42:04.111953Z] Running populate-webroot step. 18:42:04 INFO - Running pre-action listener: _resource_record_pre_action 18:42:04 INFO - Running main action method: populate_webroot 18:42:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:04 INFO - '--tppagecycles', 18:42:04 INFO - '7'], 18:42:04 INFO - 'tests': ['tsvgm']}}, 18:42:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:42:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['damp', 'tps']}, 18:42:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'tests': ['damp', 'tps']}, 18:42:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['dromaeo_dom']}, 18:42:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['basic_compositor_video']}, 18:42:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:42:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['a11yr', 18:42:04 INFO - 'ts_paint', 18:42:04 INFO - 'tpaint', 18:42:04 INFO - 'sessionrestore', 18:42:04 INFO - 'sessionrestore_no_auto_restore', 18:42:04 INFO - 'tabpaint']}, 18:42:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['tsvgx', 18:42:04 INFO - 'tsvgr_opacity', 18:42:04 INFO - 'tart', 18:42:04 INFO - 'tscrollx', 18:42:04 INFO - 'cart']}, 18:42:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:04 INFO - 'tsvgr_opacity', 18:42:04 INFO - 'tart', 18:42:04 INFO - 'tscrollx', 18:42:04 INFO - 'cart']}, 18:42:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'talos_options': ['--disable-e10s'], 18:42:04 INFO - 'tests': ['tp5o']}, 18:42:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'tests': ['tp5o']}, 18:42:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'talos_options': ['--disable-e10s', 18:42:04 INFO - '--xperf_path', 18:42:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:04 INFO - 'tests': ['tp5n']}, 18:42:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:04 INFO - 'talos_options': ['--xperf_path', 18:42:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:04 INFO - 'tests': ['tp5n']}}, 18:42:04 INFO - 'talos.zip': {'path': '', 18:42:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:04 INFO - Running post-action listener: _resource_record_post_action 18:42:04 INFO - [mozharness: 2016-10-25 01:42:04.132570Z] Finished populate-webroot step (success) 18:42:04 INFO - [mozharness: 2016-10-25 01:42:04.132660Z] Running create-virtualenv step. 18:42:04 INFO - Running pre-action listener: _resource_record_pre_action 18:42:04 INFO - Running main action method: create_virtualenv 18:42:04 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:04 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 18:42:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:04 INFO - Using real prefix '/usr' 18:42:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:04 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:06 INFO - Installing pip.................done. 18:42:06 INFO - Return code: 0 18:42:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:42:06 INFO - Using env: {'DISPLAY': ':0', 18:42:06 INFO - 'HOME': '/home/cltbld', 18:42:06 INFO - 'LANG': 'en_US.UTF-8', 18:42:06 INFO - 'LANGUAGE': 'en_US:en', 18:42:06 INFO - 'LOGNAME': 'cltbld', 18:42:06 INFO - 'MAIL': '/var/mail/cltbld', 18:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:06 INFO - 'MOZ_NO_REMOTE': '1', 18:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:06 INFO - 'NO_EM_RESTART': '1', 18:42:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:06 INFO - 'PWD': '/builds/slave/test', 18:42:06 INFO - 'SHELL': '/bin/bash', 18:42:06 INFO - 'SHLVL': '1', 18:42:06 INFO - 'TERM': 'linux', 18:42:06 INFO - 'TMOUT': '86400', 18:42:06 INFO - 'USER': 'cltbld', 18:42:06 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:06 INFO - '_': '/tools/buildbot/bin/python'} 18:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:06 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 18:42:06 INFO - Cleaning up... 18:42:06 INFO - Return code: 0 18:42:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:42:06 INFO - Using env: {'DISPLAY': ':0', 18:42:06 INFO - 'HOME': '/home/cltbld', 18:42:06 INFO - 'LANG': 'en_US.UTF-8', 18:42:06 INFO - 'LANGUAGE': 'en_US:en', 18:42:06 INFO - 'LOGNAME': 'cltbld', 18:42:06 INFO - 'MAIL': '/var/mail/cltbld', 18:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:06 INFO - 'MOZ_NO_REMOTE': '1', 18:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:06 INFO - 'NO_EM_RESTART': '1', 18:42:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:06 INFO - 'PWD': '/builds/slave/test', 18:42:06 INFO - 'SHELL': '/bin/bash', 18:42:06 INFO - 'SHLVL': '1', 18:42:06 INFO - 'TERM': 'linux', 18:42:06 INFO - 'TMOUT': '86400', 18:42:06 INFO - 'USER': 'cltbld', 18:42:06 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:06 INFO - '_': '/tools/buildbot/bin/python'} 18:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:07 INFO - Downloading/unpacking psutil>=3.1.1 18:42:07 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 18:42:07 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 18:42:07 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 18:42:07 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 18:42:07 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 18:42:07 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 18:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:09 INFO - Installing collected packages: psutil 18:42:09 INFO - Running setup.py install for psutil 18:42:09 INFO - building 'psutil._psutil_linux' extension 18:42:09 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 18:42:09 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 18:42:09 INFO - building 'psutil._psutil_posix' extension 18:42:09 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 18:42:09 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 18:42:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:09 INFO - Successfully installed psutil 18:42:09 INFO - Cleaning up... 18:42:09 INFO - Return code: 0 18:42:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:09 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:09 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 18:42:09 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 18:42:09 INFO - Using env: {'DISPLAY': ':0', 18:42:09 INFO - 'HOME': '/home/cltbld', 18:42:09 INFO - 'LANG': 'en_US.UTF-8', 18:42:09 INFO - 'LANGUAGE': 'en_US:en', 18:42:09 INFO - 'LOGNAME': 'cltbld', 18:42:09 INFO - 'MAIL': '/var/mail/cltbld', 18:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:09 INFO - 'MOZ_NO_REMOTE': '1', 18:42:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:09 INFO - 'NO_EM_RESTART': '1', 18:42:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:09 INFO - 'PWD': '/builds/slave/test', 18:42:09 INFO - 'SHELL': '/bin/bash', 18:42:09 INFO - 'SHLVL': '1', 18:42:09 INFO - 'TERM': 'linux', 18:42:09 INFO - 'TMOUT': '86400', 18:42:09 INFO - 'USER': 'cltbld', 18:42:09 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:09 INFO - '_': '/tools/buildbot/bin/python'} 18:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:42:09 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 18:42:09 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 18:42:09 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 18:42:09 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 18:42:09 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 18:42:09 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 18:42:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:42:11 INFO - Installing collected packages: mozsystemmonitor 18:42:11 INFO - Running setup.py install for mozsystemmonitor 18:42:11 INFO - Successfully installed mozsystemmonitor 18:42:11 INFO - Cleaning up... 18:42:12 INFO - Return code: 0 18:42:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:12 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 18:42:12 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 18:42:12 INFO - Using env: {'DISPLAY': ':0', 18:42:12 INFO - 'HOME': '/home/cltbld', 18:42:12 INFO - 'LANG': 'en_US.UTF-8', 18:42:12 INFO - 'LANGUAGE': 'en_US:en', 18:42:12 INFO - 'LOGNAME': 'cltbld', 18:42:12 INFO - 'MAIL': '/var/mail/cltbld', 18:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:12 INFO - 'MOZ_NO_REMOTE': '1', 18:42:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:12 INFO - 'NO_EM_RESTART': '1', 18:42:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:12 INFO - 'PWD': '/builds/slave/test', 18:42:12 INFO - 'SHELL': '/bin/bash', 18:42:12 INFO - 'SHLVL': '1', 18:42:12 INFO - 'TERM': 'linux', 18:42:12 INFO - 'TMOUT': '86400', 18:42:12 INFO - 'USER': 'cltbld', 18:42:12 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:12 INFO - '_': '/tools/buildbot/bin/python'} 18:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:12 INFO - Downloading/unpacking jsonschema==2.5.1 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 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 18:42:14 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:14 INFO - warning: no files found matching '*.txt' 18:42:14 INFO - no previously-included directories found matching 'build' 18:42:14 INFO - no previously-included directories found matching 'dist' 18:42:14 INFO - no previously-included directories found matching '.git*' 18:42:14 INFO - Installing collected packages: jsonschema, functools32 18:42:14 INFO - Running setup.py install for functools32 18:42:14 INFO - warning: no files found matching '*.txt' 18:42:14 INFO - no previously-included directories found matching 'build' 18:42:14 INFO - no previously-included directories found matching 'dist' 18:42:14 INFO - no previously-included directories found matching '.git*' 18:42:14 INFO - Successfully installed jsonschema functools32 18:42:14 INFO - Cleaning up... 18:42:14 INFO - Return code: 0 18:42:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:14 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 18:42:14 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 18:42:14 INFO - Using env: {'DISPLAY': ':0', 18:42:14 INFO - 'HOME': '/home/cltbld', 18:42:14 INFO - 'LANG': 'en_US.UTF-8', 18:42:14 INFO - 'LANGUAGE': 'en_US:en', 18:42:14 INFO - 'LOGNAME': 'cltbld', 18:42:14 INFO - 'MAIL': '/var/mail/cltbld', 18:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:14 INFO - 'MOZ_NO_REMOTE': '1', 18:42:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:14 INFO - 'NO_EM_RESTART': '1', 18:42:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:14 INFO - 'PWD': '/builds/slave/test', 18:42:14 INFO - 'SHELL': '/bin/bash', 18:42:14 INFO - 'SHLVL': '1', 18:42:14 INFO - 'TERM': 'linux', 18:42:14 INFO - 'TMOUT': '86400', 18:42:14 INFO - 'USER': 'cltbld', 18:42:14 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:14 INFO - '_': '/tools/buildbot/bin/python'} 18:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:42:14 INFO - Cleaning up... 18:42:14 INFO - Return code: 0 18:42:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:14 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:14 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 18:42:14 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 18:42:14 INFO - Using env: {'DISPLAY': ':0', 18:42:14 INFO - 'HOME': '/home/cltbld', 18:42:14 INFO - 'LANG': 'en_US.UTF-8', 18:42:14 INFO - 'LANGUAGE': 'en_US:en', 18:42:14 INFO - 'LOGNAME': 'cltbld', 18:42:14 INFO - 'MAIL': '/var/mail/cltbld', 18:42:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:14 INFO - 'MOZ_NO_REMOTE': '1', 18:42:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:14 INFO - 'NO_EM_RESTART': '1', 18:42:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:14 INFO - 'PWD': '/builds/slave/test', 18:42:14 INFO - 'SHELL': '/bin/bash', 18:42:14 INFO - 'SHLVL': '1', 18:42:14 INFO - 'TERM': 'linux', 18:42:14 INFO - 'TMOUT': '86400', 18:42:14 INFO - 'USER': 'cltbld', 18:42:14 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:14 INFO - '_': '/tools/buildbot/bin/python'} 18:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:15 INFO - Downloading/unpacking blobuploader==1.2.4 18:42:15 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 18:42:15 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 18:42:15 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 18:42:15 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 18:42:15 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 18:42:15 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 18:42:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 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 18:42:17 INFO - Downloading docopt-0.6.1.tar.gz 18:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:17 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:17 INFO - Running setup.py install for blobuploader 18:42:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:17 INFO - Running setup.py install for requests 18:42:18 INFO - Running setup.py install for docopt 18:42:18 INFO - Successfully installed blobuploader requests docopt 18:42:18 INFO - Cleaning up... 18:42:18 INFO - Return code: 0 18:42:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:18 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:18 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 18:42:18 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 18:42:18 INFO - Using env: {'DISPLAY': ':0', 18:42:18 INFO - 'HOME': '/home/cltbld', 18:42:18 INFO - 'LANG': 'en_US.UTF-8', 18:42:18 INFO - 'LANGUAGE': 'en_US:en', 18:42:18 INFO - 'LOGNAME': 'cltbld', 18:42:18 INFO - 'MAIL': '/var/mail/cltbld', 18:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:18 INFO - 'MOZ_NO_REMOTE': '1', 18:42:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:18 INFO - 'NO_EM_RESTART': '1', 18:42:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:18 INFO - 'PWD': '/builds/slave/test', 18:42:18 INFO - 'SHELL': '/bin/bash', 18:42:18 INFO - 'SHLVL': '1', 18:42:18 INFO - 'TERM': 'linux', 18:42:18 INFO - 'TMOUT': '86400', 18:42:18 INFO - 'USER': 'cltbld', 18:42:18 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:18 INFO - '_': '/tools/buildbot/bin/python'} 18:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:18 INFO - Running setup.py (path:/tmp/pip-lXXFUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:18 INFO - Running setup.py (path:/tmp/pip-W_1bMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:18 INFO - Running setup.py (path:/tmp/pip-nGpC6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:18 INFO - Running setup.py (path:/tmp/pip-PHKri4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:18 INFO - Running setup.py (path:/tmp/pip-rUEtKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:18 INFO - Running setup.py (path:/tmp/pip-A9cCHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:19 INFO - Running setup.py (path:/tmp/pip-oIVBCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:19 INFO - Running setup.py (path:/tmp/pip-W1PrbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:19 INFO - Running setup.py (path:/tmp/pip-HDfFdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:19 INFO - Running setup.py (path:/tmp/pip-pKrxCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:19 INFO - Running setup.py (path:/tmp/pip-Uzetw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:19 INFO - Running setup.py (path:/tmp/pip-9Su7yZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:19 INFO - Running setup.py (path:/tmp/pip-6PADgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:19 INFO - Running setup.py (path:/tmp/pip-1g2Fjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:19 INFO - Running setup.py (path:/tmp/pip-YupQqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:19 INFO - Running setup.py (path:/tmp/pip-dfLbDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:19 INFO - Running setup.py (path:/tmp/pip-xpnPt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:19 INFO - Running setup.py install for manifestparser 18:42:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:19 INFO - Running setup.py install for mozcrash 18:42:20 INFO - Running setup.py install for mozdebug 18:42:20 INFO - Running setup.py install for mozdevice 18:42:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for mozfile 18:42:20 INFO - Running setup.py install for mozhttpd 18:42:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for mozinfo 18:42:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for mozInstall 18:42:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for mozleak 18:42:20 INFO - Running setup.py install for mozlog 18:42:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for moznetwork 18:42:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:20 INFO - Running setup.py install for mozprocess 18:42:21 INFO - Running setup.py install for mozprofile 18:42:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:21 INFO - Running setup.py install for mozrunner 18:42:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:21 INFO - Running setup.py install for mozscreenshot 18:42:21 INFO - Running setup.py install for moztest 18:42:21 INFO - Running setup.py install for mozversion 18:42:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:21 INFO - Cleaning up... 18:42:21 INFO - Return code: 0 18:42:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:21 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:21 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 18:42:21 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 18:42:21 INFO - Using env: {'DISPLAY': ':0', 18:42:21 INFO - 'HOME': '/home/cltbld', 18:42:21 INFO - 'LANG': 'en_US.UTF-8', 18:42:21 INFO - 'LANGUAGE': 'en_US:en', 18:42:21 INFO - 'LOGNAME': 'cltbld', 18:42:21 INFO - 'MAIL': '/var/mail/cltbld', 18:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:21 INFO - 'MOZ_NO_REMOTE': '1', 18:42:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:21 INFO - 'NO_EM_RESTART': '1', 18:42:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:21 INFO - 'PWD': '/builds/slave/test', 18:42:21 INFO - 'SHELL': '/bin/bash', 18:42:21 INFO - 'SHLVL': '1', 18:42:21 INFO - 'TERM': 'linux', 18:42:21 INFO - 'TMOUT': '86400', 18:42:21 INFO - 'USER': 'cltbld', 18:42:21 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:21 INFO - '_': '/tools/buildbot/bin/python'} 18:42:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:21 INFO - Running setup.py (path:/tmp/pip-9fU2QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:21 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)) 18:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:21 INFO - Running setup.py (path:/tmp/pip-ac8582-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:22 INFO - Running setup.py (path:/tmp/pip-Yp6Rb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:22 INFO - Running setup.py (path:/tmp/pip-8s38AK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:22 INFO - Running setup.py (path:/tmp/pip-t6NdoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:22 INFO - Running setup.py (path:/tmp/pip-qqaAW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:22 INFO - Running setup.py (path:/tmp/pip-dqn_bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:22 INFO - Running setup.py (path:/tmp/pip-uWClKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:22 INFO - Running setup.py (path:/tmp/pip-fW1sdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:22 INFO - Running setup.py (path:/tmp/pip-SxK5wM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:22 INFO - Running setup.py (path:/tmp/pip-x3lFoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:22 INFO - Running setup.py (path:/tmp/pip-aOsKpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:22 INFO - Running setup.py (path:/tmp/pip-V9Fo2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:22 INFO - Running setup.py (path:/tmp/pip-iFFOaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:22 INFO - Running setup.py (path:/tmp/pip-sIU55I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:22 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)) 18:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:22 INFO - Running setup.py (path:/tmp/pip-UlOVMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:23 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)) 18:42:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:23 INFO - Running setup.py (path:/tmp/pip-4dhG4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:23 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)) 18:42:23 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)) 18:42:23 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)) 18:42:23 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)) 18:42:23 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)) 18:42:23 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)) 18:42:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:23 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 18:42:23 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 18:42:23 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 18:42:23 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 18:42:23 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 18:42:23 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 18:42:25 INFO - Downloading blessings-1.6.tar.gz 18:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:25 INFO - Installing collected packages: blessings 18:42:25 INFO - Running setup.py install for blessings 18:42:25 INFO - Successfully installed blessings 18:42:25 INFO - Cleaning up... 18:42:25 INFO - Return code: 0 18:42:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:25 INFO - Reading from file tmpfile_stdout 18:42:25 INFO - Current package versions: 18:42:25 INFO - argparse == 1.2.1 18:42:25 INFO - blessings == 1.6 18:42:25 INFO - blobuploader == 1.2.4 18:42:25 INFO - docopt == 0.6.1 18:42:25 INFO - functools32 == 3.2.3-2 18:42:25 INFO - jsonschema == 2.5.1 18:42:25 INFO - manifestparser == 1.1 18:42:25 INFO - mozInstall == 1.12 18:42:25 INFO - mozcrash == 0.17 18:42:25 INFO - mozdebug == 0.1 18:42:25 INFO - mozdevice == 0.48 18:42:25 INFO - mozfile == 1.2 18:42:25 INFO - mozhttpd == 0.7 18:42:25 INFO - mozinfo == 0.9 18:42:25 INFO - mozleak == 0.1 18:42:25 INFO - mozlog == 3.3 18:42:25 INFO - moznetwork == 0.27 18:42:25 INFO - mozprocess == 0.23 18:42:25 INFO - mozprofile == 0.28 18:42:25 INFO - mozrunner == 6.12 18:42:25 INFO - mozscreenshot == 0.1 18:42:25 INFO - mozsystemmonitor == 0.3 18:42:25 INFO - moztest == 0.7 18:42:25 INFO - mozversion == 1.4 18:42:25 INFO - psutil == 3.1.1 18:42:25 INFO - requests == 1.2.3 18:42:25 INFO - wsgiref == 0.1.2 18:42:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 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 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:25 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 18:42:25 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 18:42:25 INFO - Using env: {'DISPLAY': ':0', 18:42:25 INFO - 'HOME': '/home/cltbld', 18:42:25 INFO - 'LANG': 'en_US.UTF-8', 18:42:25 INFO - 'LANGUAGE': 'en_US:en', 18:42:25 INFO - 'LOGNAME': 'cltbld', 18:42:25 INFO - 'MAIL': '/var/mail/cltbld', 18:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:25 INFO - 'MOZ_NO_REMOTE': '1', 18:42:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:25 INFO - 'NO_EM_RESTART': '1', 18:42:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:25 INFO - 'PWD': '/builds/slave/test', 18:42:25 INFO - 'SHELL': '/bin/bash', 18:42:25 INFO - 'SHLVL': '1', 18:42:25 INFO - 'TERM': 'linux', 18:42:25 INFO - 'TMOUT': '86400', 18:42:25 INFO - 'USER': 'cltbld', 18:42:25 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:25 INFO - '_': '/tools/buildbot/bin/python'} 18:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 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)) 18:42:25 INFO - Cleaning up... 18:42:25 INFO - Return code: 0 18:42:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bf4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eb5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, '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': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', '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 18:42:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:42:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:42:25 INFO - Using env: {'DISPLAY': ':0', 18:42:25 INFO - 'HOME': '/home/cltbld', 18:42:25 INFO - 'LANG': 'en_US.UTF-8', 18:42:25 INFO - 'LANGUAGE': 'en_US:en', 18:42:25 INFO - 'LOGNAME': 'cltbld', 18:42:25 INFO - 'MAIL': '/var/mail/cltbld', 18:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:25 INFO - 'MOZ_NO_REMOTE': '1', 18:42:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:25 INFO - 'NO_EM_RESTART': '1', 18:42:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:25 INFO - 'PWD': '/builds/slave/test', 18:42:25 INFO - 'SHELL': '/bin/bash', 18:42:25 INFO - 'SHLVL': '1', 18:42:25 INFO - 'TERM': 'linux', 18:42:25 INFO - 'TMOUT': '86400', 18:42:25 INFO - 'USER': 'cltbld', 18:42:25 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:25 INFO - '_': '/tools/buildbot/bin/python'} 18:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:42:26 INFO - Cleaning up... 18:42:26 INFO - Return code: 0 18:42:26 INFO - Running post-action listener: _resource_record_post_action 18:42:26 INFO - Running post-action listener: _start_resource_monitoring 18:42:26 INFO - Starting resource monitoring. 18:42:26 INFO - [mozharness: 2016-10-25 01:42:26.052065Z] Finished create-virtualenv step (success) 18:42:26 INFO - [mozharness: 2016-10-25 01:42:26.052545Z] Running install step. 18:42:26 INFO - Running pre-action listener: _resource_record_pre_action 18:42:26 INFO - Running main action method: install 18:42:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:26 INFO - Reading from file tmpfile_stdout 18:42:26 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:26 INFO - Reading from file tmpfile_stdout 18:42:26 INFO - Output received: 18:42:26 INFO - Usage: mozinstall [options] installer 18:42:26 INFO - Options: 18:42:26 INFO - -h, --help show this help message and exit 18:42:26 INFO - -d DEST, --destination=DEST 18:42:26 INFO - Directory to install application into. [default: 18:42:26 INFO - "/builds/slave/test"] 18:42:26 INFO - --app=APP Application being installed. [default: firefox] 18:42:26 INFO - mkdir: /builds/slave/test/build/application 18:42:26 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'] 18:42:26 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 18:42:40 INFO - Reading from file tmpfile_stdout 18:42:40 INFO - Output received: 18:42:40 INFO - /builds/slave/test/build/application/firefox/firefox 18:42:40 INFO - Running post-action listener: _resource_record_post_action 18:42:40 INFO - [mozharness: 2016-10-25 01:42:40.426219Z] Finished install step (success) 18:42:40 INFO - [mozharness: 2016-10-25 01:42:40.426498Z] Running run-tests step. 18:42:40 INFO - Running pre-action listener: _resource_record_pre_action 18:42:40 INFO - Running main action method: run_tests 18:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:42:40 INFO - Python 2.7.3 18:42:40 INFO - Return code: 0 18:42:40 INFO - grabbing minidump binary from tooltool 18:42:40 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')]} 18:42:40 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 0x1eb33b0>, '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 0x1eb42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:42:40 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 18:42:40 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 18:42:40 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 18:42:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:42:40 INFO - Return code: 0 18:42:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:42:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:42:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:42:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:42:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:42:40 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/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 18:42:40 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/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --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 18:42:40 INFO - Using env: {'DISPLAY': ':0', 18:42:40 INFO - 'HOME': '/home/cltbld', 18:42:40 INFO - 'LANG': 'en_US.UTF-8', 18:42:40 INFO - 'LANGUAGE': 'en_US:en', 18:42:40 INFO - 'LOGNAME': 'cltbld', 18:42:40 INFO - 'MAIL': '/var/mail/cltbld', 18:42:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:40 INFO - 'MOZ_NO_REMOTE': '1', 18:42:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:40 INFO - 'NO_EM_RESTART': '1', 18:42:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:40 INFO - 'PWD': '/builds/slave/test', 18:42:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:40 INFO - 'SHELL': '/bin/bash', 18:42:40 INFO - 'SHLVL': '1', 18:42:40 INFO - 'TERM': 'linux', 18:42:40 INFO - 'TMOUT': '86400', 18:42:40 INFO - 'USER': 'cltbld', 18:42:40 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177', 18:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:40 INFO - '_': '/tools/buildbot/bin/python'} 18:42:40 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/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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 18:42:40 INFO - mozversion application_buildid: 20161024155222 18:42:40 INFO - mozversion application_changeset: 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 18:42:40 INFO - mozversion application_display_name: Firefox 18:42:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:42:40 INFO - mozversion application_name: Firefox 18:42:40 INFO - mozversion application_remotingname: firefox 18:42:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:42:40 INFO - mozversion application_vendor: Mozilla 18:42:40 INFO - mozversion application_version: 50.0 18:42:40 INFO - mozversion platform_buildid: 20161024155222 18:42:40 INFO - mozversion platform_changeset: 4131f9c0b0c7a1cafc8fa15d44d159d9e3bd3f60 18:42:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:42:40 INFO - mozversion platform_version: 50.0 18:42:40 INFO - using testdate: 1477359760 18:42:40 INFO - actual date: 1477359760 18:42:40 INFO - starting webserver on 'localhost:37425' 18:42:40 INFO - SUITE-START | Running 2 tests 18:42:40 INFO - TEST-START | dromaeo_css 18:42:40 INFO - operating with platform_type : linux_ 18:42:40 INFO - Initialising browser for dromaeo_css test... 18:42:40 INFO - TEST-INFO | started process 3096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOO1lUT/profile http://localhost:37425/getInfo.html) 18:42:44 INFO - PROCESS | 3096 | __metrics Screen width/height:1600/1200 18:42:44 INFO - PROCESS | 3096 | colorDepth:24 18:42:44 INFO - PROCESS | 3096 | Browser inner width/height: 1024/697 18:42:44 INFO - PROCESS | 3096 | __metrics 18:42:45 INFO - PROCESS | 3096 | 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" 18:42:45 INFO - PROCESS | 3096 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:42:46 INFO - PROCESS | 3096 | console.error: PushService: 18:42:46 INFO - PROCESS | 3096 | stateChangeProcessEnqueue: Error transitioning state 18:42:46 INFO - PROCESS | 3096 | UnknownError 18:42:46 INFO - TEST-INFO | 3096: exit 0 18:42:46 INFO - Browser initialized. 18:42:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:42:46 INFO - TEST-INFO | started process 3181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOO1lUT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:42:58 INFO - PROCESS | 3181 | RSS: Main: 144220160 18:42:58 INFO - PROCESS | 3181 | 18:43:49 INFO - PROCESS | 3181 | 1477359829786 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] 18:45:57 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37425/tests/dromaeo/cssquery-ext.html) 18:45:59 INFO - PROCESS | 3181 | RSS: Main: 255926272 18:45:59 INFO - PROCESS | 3181 | 18:49:01 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-ext.html (next: http://localhost:37425/tests/dromaeo/cssquery-jquery.html) 18:49:02 INFO - PROCESS | 3181 | RSS: Main: 229552128 18:49:02 INFO - PROCESS | 3181 | 18:52:03 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37425/tests/dromaeo/cssquery-mootools.html) 18:52:04 INFO - PROCESS | 3181 | RSS: Main: 176132096 18:52:04 INFO - PROCESS | 3181 | 18:55:06 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37425/tests/dromaeo/cssquery-prototype.html) 18:55:06 INFO - PROCESS | 3181 | RSS: Main: 226062336 18:55:06 INFO - PROCESS | 3181 | 18:58:09 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37425/tests/dromaeo/cssquery-yui.html) 18:58:10 INFO - PROCESS | 3181 | RSS: Main: 177295360 18:58:10 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | Cycle 1(1): loaded http://localhost:37425/tests/dromaeo/cssquery-yui.html (next: http://localhost:37425/tests/dromaeo/cssquery-dojo.html) 19:01:12 INFO - PROCESS | 3181 | RSS: Main: 265474048 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | __start_tp_report 19:01:12 INFO - PROCESS | 3181 | _x_x_mozilla_page_load 19:01:12 INFO - PROCESS | 3181 | _x_x_mozilla_page_load_details 19:01:12 INFO - PROCESS | 3181 | |i|pagename|runs| 19:01:12 INFO - PROCESS | 3181 | |0;dojo.html;3196.744260459163;3200.9199770005753;3221.7422606191494;3258.837058147092;3271.787333823301;5528.17077438384;5628.802991895274;5632.7746890124345;5632.9999999999945;5640.0693885508845;5572.916406253899;5639.971800141004;5644.943550564493;5645.491905728483;5679.517241034517;5058.772355244014;5105.795768169269;5129.153689641204;5170.896582068356;5184.377874655047;4993.825216117437;5034.043531728978;5178.922316165242;5205.16717325228;5205.401378841425;5117.46266642001;5118.744062796864;5173.896522069556;5220.347456567929;5253.106971814782;5233.450487698794;5268.025415298163;5275.762590683428;5275.86810329741;5303.363596368422;4961.355023846912;5089.312942752718;5163.767630456601;5241.449647786965;5254.0805359062015;5416.187571864252;5466.070767969415;5495.807646732385;5528.944710552883;5594.832155035356;5941.494972927307;5982.820515384546;5996.280446346466;5997.910031349533;6001.939980600182;5737.770489180428;6041.486473649745;6048.727807248684;6048.818535443944;6055.212822333113;5510.476504732044;5514.91727624086;5557.666540007613;5619.971900140555;5664.915026274609;6115.327313995457;6119.892395302511;6127.387261273926;6128.050152226412;6132.325444201134;5651.491365777099;5888.941110588925;5893.5579831512805;5938.673372964528;5940.108983652487;5870.970645146747;5875.265591801025;5934.080217566284;5991.2510936132985;6041.2750469943885;5905.320888097924;5937.109433584997;5955.464008239279;5956.225690660273;5961.463468287875;5822.039363504974;5959.404059594092;5959.970200148971;5973.880522389528;5979.252593425822;4410.867673969786;4433.512313645497;4438.400815889878;4451.287793952952;4507.301368287921;4057.4522439470884;4058.1072164123843;4059.330210515291;4062.106336605942;4083.5916408358926;5580.246666700024;5582.497575218169;5584.497395234367;5590.524805391506;5611.242482264924;5773.3072031356505;5931.228940237742;5945.702714864239;5961.970190149022;6087.543434242361;5640.633358831663;5644.5484361251;5644.8306550803545;5670.886582268332;5674.347450043298;5056.367954005749;5063.518965698253;5069.340985671839;5088.211327244283;5094.872628184325;5980.611260268156;6035.7283922223605;6129.267100814712;6132.692058233554;6232.719527621268;6029.487493563096;6032.6983650818165;6033.064874944391;6036;6056.424639659225;6182.258129024546;6216.937830621817;6225.501959843111;6235.875282494416;6239.96880015597;5697.743601537858;5829.417058294137;5847.38602446754;5921.437463440966;5928.051511758098;5990.31111422192;5999.370066142992;6000.669963152069;6005.219321488178;6027.819165425044;5861.267341582302;5866.853328666647;5870.911936320873;5871.383504731942;5874.765009399576;5724.255846740064;5782.2483077199695;5836.124581312837;5837.766489340379;5866.090755932837;5823.650580965155;5876.030454974882;5877.882442351214;5882.029465138205;5900.262467191601;5857.297124345106;5904.911426328523;5921.822345329647;5929.080992446177;5930.466258036798;5544.0575102231915;5582.3859375467855;5588.245586845724;5614.831555053355;5772.018756811436 19:01:12 INFO - PROCESS | 3181 | |1;ext.html;592.8725324055328;626.4143026270436;654.1300070905696;656.3567703650423;674.4671709349614;34400;46691.29963050553;47483.525164748346;47623.76188119063;47643.76178119113;48317.75841120789;48636.2704559431;48690.75654621722;48771.26843097356;49010;8512.106228845974;9317.114874086968;9472;9481.09929556691;11072.446377681084;10998.34009959401;11268;11276.718082047973;11278.830817537744;11284.661460156209;11300.378479183642;11344.376059316734;11345.432728363548;11358.71603209918;11365.431728413587;11272.267302625343;11321.886781132165;11323.660290191307;11343;11348.262362946423;11079;11159.051480624157;11159.609413670483;11179.105671546338;11180.273282236629;45226.32160517595;45684.314735279;45690;45765.31352029722;45835;3487.1805125795418;3582.247727977128;3611.494390785292;3657.7256705747177;3750.5124333836698;34619.307613847835;34660.72505595179;34761.82619086889;35073;36050.27899441996;4652.790624421909;4720.12677654635;4766.404199475066;4773.331733557305;4780.163471392527;356621.2168939165;356654;357803;357830;358226;63521.36478635154;63980.68009659922;64004.67997659982;64012.99999999907;64159;39169.804150979064;40307.395389069185;40484.79757601252;42560.78719606382;42642.14715705669;19509.31717389913;19516.41450756451;19577.314793982143;19780.208791648172;19783.307584234484;13715.794263086062;14092.647683807987;14101.576952691436;14166.079204851652;14997.475088371855;20066;20483;20780.792192077886;20981;21421.357359279245;7435.925640743631;7583.317501424898;7661.42539309554;7682.423818213656;7793.220677932161;976.7802244495074;1040.5265604150093;1067.690369792767;1141.785872768155;1207.3057991654834;34578.65421345754;34876.65123348734;34906;35135.29729405398;35178.64821351805;1148.8276758486127;1172.8240763885315;1177.3936422742295;1207.8188271759307;1216.8136067334456;17309;18339;18384.908075459804;18540.907295463436;18558.907205464155;603923;608475;609064;610016;613293;62001.37998619956;62664;62744;62802;62824;1336.6791969927342;1423.1390009044585;1433.3120102350929;1454.789055586955;1457.5991602309239;33467;35150.29699405946;35918.8204058978;35952.46071308985;36203.81898090493;52945.470545294054;53130;53412.46587534075;53530.732346338016;53563;423473;427909;428204;428299;429801;27881.302967425978;28035.439291213654;28061.298467538476;29165.56251656269;29467.85266073656;45761;51199;52139;52430;53144.734276328374;43096.35355469733;44667;44730.329045065;44923.77538112288;46265.53734462612;42713;42935.35596965985;43828.56171438245;44564.77717611391;45309;43875.341869871336;44087.77956110199;44450;44676.77661611671;45093;37347;38759;39093.80453097716;39698.80150599228;39944.40083398693;29585.26036849096;29590.852045739633;29751;29754.553681695226;29755.702442975293 19:01:12 INFO - PROCESS | 3181 | |2;jquery.html;408.66897812771657;414.75736694033986;426.349816529792;432.2975165356297;438.04943273097376;18899.71650425243;20276.797232027693;20807.687884681724;21391.465213369673;21603.783962160414;15627.531174064796;19484.51288717783;20055.799442005613;20166.19335226589;20400.183992640275;5352.116900711388;5551.750171242284;5552.3892371839065;5555.916661250085;5557.194206840006;5411.729413529328;5471.069918113911;5473.425290344516;5694.2028116063575;5933.13969474426;5964.075568286901;6022.6687532185715;6029.909551356734;6036.185115009462;6038.969805150968;5882.2353094097925;5989.251343582052;5993.490553302974;5997.5501837361935;6031.577789554733;5805.36141024487;6168.938310616882;6214.533909956726;6217.875642487125;6224.097505861625;13455.259960702157;13601.387937542835;13715.382807773674;14360.425582976668;14726.116433014053;2580.509703156394;2953.4536110819354;2959.334149816296;3074.2621770775077;3142.073088438916;10689.732756681067;10788.78422431547;10819.78360432787;10834.40410777407;11037.724056898642;1949;1959.588486417854;2031.3499680102373;2048.8667640424806;2071.7099606055162;57262.42737572654;79735;80788;83106;88966.11033889708;11538;11569.710757230969;11633.243839150427;11842.230255033395;12023.819642705366;15017.474388396573;16086.517404477885;16872.156392180343;16983.23575439108;16997.745033824503;6848.280930502325;6912.619805910623;8908.331875109392;8992.505412202261;9318.068193180763;8960.417572857743;8998.50508222041;9008.099190080939;9237.445753254826;9240.8613870792;4678.532146785295;4983.725895075733;5001.274815151855;5009.373828271466;5149.922751158736;1845.1696736468643;1974.7136665183468;1996.391100714296;2058.886761228147;2069.4826293426645;975.2977855943709;992.5434300221834;998.9900100999042;1052.1109662335314;1067.8718553773442;20377.796222037887;20590.897045514677;20620.587588248152;20839.79160208409;20947.37157885266;962.2206013129388;998.3810037776625;1019.5361110694616;1027.6300531808542;1057.101463755817;10847.40339281347;11544.134189935789;11579.421028948687;11674.94162529199;11697;500295;506877;506912;537408;577115;95702.52148739212;98525.50737246411;106794;107307;107570;1058.222206678091;1062.9362238265574;1102.6306187427301;1106.9059129974041;1108.9611863584735;17469;18431.907840460713;18476;19142.61714765725;19176;44935.77532112449;45283.54716452793;45315;45660.08679826318;47965.76017119892;57976.4202357971;63594.68202658957;63842;65600;65871.67064164649;8275.379346549153;8421.87367189505;8447.155284471504;8882.37823352385;9076.455412675261;9733.853992190265;12531.812022819482;13395.866041339852;13580.86419135796;13640.454381824617;11505.079593632321;11648;11745.295282282748;11836.230645008047;11997.64007079789;5500.862478438071;10452.738681532719;11445.656630301104;11758.529658813552;11806;12132.696682583006;12153.513859445524;12208.389580520761;12223.75552488963;12361.93819030899;8511.829763404821;8558.101399353227;8667.479951202948;8879.866801997847;8986.86519702218;10632.946835265775;11345.659630211107;11752.58865939688;11842;12140 19:01:12 INFO - PROCESS | 3181 | |3;mootools.html;1398.7831886057659;1457.4170331867251;1586.159335552157;1743.9710570763248;1831.2400353853152;9026.50354230517;9731.270154738406;9755.902440975588;9757.951210243938;9766.462844543546;8424.620892059856;9022.819543609125;9284.025177356381;9332.113449222314;9491.477968711719;2502.812289078322;2553.259554729126;2555.399481121935;2555.9744402556016;2556.7954563634867;2458.889349979246;2560.9231723048338;2561.4108754986364;2566.4097257630756;2567.3838278813046;2522.2181123851665;2527.9620805687837;2538.987305063472;2540.4665020345656;2540.5554028045112;2347.9882600587057;2355.199232261022;2357.5167090746354;2400.7119145702454;2406.7111946566347;2407.9759202407927;2414.420539070628;2425.1027119965547;2425.466397392571;2445.6698345723457;9242.353035287533;9540.95229523848;9651.372660777097;9657.082577155159;9671.758206044835;5685.602007859451;5839.270091238595;5842.006858834008;5843.2695913010875;5846.298444186682;3584.713222942158;3743.906402339936;3744.588095309514;3757.9436308455397;3814.084619691254;1159.7069267766465;1191.559123124441;1208.546794951893;1209.897158741508;1214.5688280660415;13343.132696374705;13343.733125337536;13356.131851429625;13359.465621375133;13361.198328100249;4879.121758083508;4956.4547899731;4973.751312434364;4994.675346102565;5169.638125331266;11191.384473854016;11197;11225.60710375133;11226.382548959886;11233.606823761293;5696.259486266758;5697.686627235459;5702.515286200636;5702.714864256771;5703.258576385043;4703.223968045234;5157.922631160535;5204.947950520446;5282.366116066097;5289.497497737709;4071.898202544901;4102.589741025933;4162.895927601834;4197.160567886435;4197.202531519001;1326.065124087516;1339.450825161679;1346.414309775251;1358.7825947848296;1366.6173471428017;907.8502047162276;942.6559305853314;1020.9591616335264;1062.081299675775;1081.0756802933536;6473.28793832678;6485.935140648533;6616.966915165393;6691.698873550702;6825.180978282638;1024.4775164665925;1033.395463653756;1044.4568824211367;1044.8537204791335;1067.8451624514403;4466.865994020184;4477.843275485342;4493.078918821634;4538;4539.6368290536675;11700.239484433485;12017.218880772722;12018.33899135556;12040.578579749666;12047.457864396123;11007.669769906915;11361.318320900773;11910.344931028876;11945.641630751092;12037.638870833889;8762.255208307237;8968.596413161424;8982.191602755784;8982.505962172012;8991.370604057784;6323.494120470444;6416.48668106541;6448.065030570448;6482.740690372332;6528.836779080371;10817.56729730802;10960.725981850199;11019.944900275768;11032.062274706419;11085.22403431752;10875.456227188768;11101.222914395914;11124.833127502932;11152.16358773079;11294.152938529478;7421.591812450367;7568.5458872467825;7594.5063570867715;8143.18568143181;8291.668333266602;9055.547222638974;9416.152546270869;9454.33819632619;9465.905340946778;9477.052294770469;8102.878456823035;8130.471519351223;8169.714060008109;8304.750857474286;8312.916870831456;8113.634886430007;8156.877646835183;8169.79575510617;8187.590620468834;8338.332933365195;7248.42012638977;7249.275072492708;7339;7342.045534080536;7392.96303518479;5724.599278050497;5862.94137058624;5881.705914704162;5901.852453688693;6032.668203248863;5607.943920560742;5740;5752.223449834219;5812.157237200667;5887.146363777143 19:01:12 INFO - PROCESS | 3181 | |4;prototype.html;334.98492567834444;347.0715835140997;349.5805033959249;351.71335361680246;352.74426041120194;5491.23122762813;5573.888522229553;5590.972045139779;5602.32772067352;5609.607327487068;5481.588880833933;5487.917681234775;5506.449355064491;5508.228847961289;5515.641483303582;4143.482064741907;4383.3863259143745;4406.97796511017;4413.1173765246995;4422.579854913778;3904.7657140571514;4320.97839510802;4335.9566404335865;4340.370646256291;4343.153085148398;4289.571042895701;4291.527931927501;4314.331278651814;4316.223079845627;4317.13657268546;4298.290782020963;4310.137972405531;4344.978275108636;4359.149965756678;4361.978190109061;3636;3763.7553559018575;3776.2636285924254;3805.714571407128;3870.2646497165447;5311.389190243094;5319.335083114611;5324.787008519655;5356.866078348034;5369.62412631116;4933.199382225498;4933.481984391659;4935.35840340757;4958.628102892261;4970.403551573798;5535.667859928417;5784.88430231393;5786.247787787603;5792.8262152135085;5793.275840519935;4202.138561594866;4206.453161089069;4212.705110642256;4213.53651098382;4214.557471465482;5813.970930145365;5923.170756094193;6086.056661217518;6086.969565152191;6108.877822443526;4215.747055176638;4218.915621687549;4225;4236.322188449833;4252.149570085995;4304.569543045733;4312.956870431318;4322.935155972663;4323.524412314643;4332.263515202423;4823.083614113307;4937.876553086202;4938.629602779806;4944.3572335596145;4972.57733092684;5757.280339957505;5764.827055188351;5782.537397008229;5794.797976768885;5883.558733094949;1453.2080016391092;1469.4930249064055;1476.1733429279639;1487.605784467117;1490.2921112471706;911.470668893825;939.5537119868145;952.0051546134306;953.9713808585753;966.6906589891167;4948.925766113512;4967.230079337708;4979.634673530117;4989.727619457088;4994.902141100746;5216.634835561475;5223.242629818655;5237.397699264558;5265.578753699695;5275.944811037808;4941.888075183127;5045.167547354646;5062.873428164326;5284.5772338212855;5690.374058853606;3173.4763763979145;3185.61772587291;3187;3196.5045417960255;3204.7275981541366;6053.545984051126;6158.692065396802;6210.441060304594;6256.812295631138;6292.46514046302;6069;6073.210482637317;6094.177286066413;6103.694815259308;6108.725107370179;6114.960456722368;6139.2325959255095;6161.291451483047;6212.658303793335;6215.378462153839;5817.505512031525;5969.283685957713;5970.567063904718;5989.251343582052;6030.48740857032;5831.475167234969;5894.587378883609;5904.468597826216;5911.881762364643;5926.140709597171;5942.821715348374;5951.285845698544;6131.662758548323;6138.447539721446;6181.474574661204;4265.850695225652;4282.972086699107;4290.292101803292;4310.555963752178;4325.307950727868;5787.594868359174;6100.786472473442;6106.45041946227;6112.449879510687;6116.17431646722;5754.503829004572;5814.244148260699;5822.5633077518505;5854.4730974212525;5882.794102206402;5824.271966004249;5837.241158649348;5837.620554663933;5875.706214689334;5892.410758924073;5687.345955215038;5809.419058094157;5821.505172060422;5844.415558444121;5868.2958045033165;5808.448197421239;5850.678212698343;5855.38518455556;5859.326177489473;5874.676892770939;5586.357568879631;5737.512311453406;5760.596758226884;5763.452472015152;5770.682612456355 19:01:12 INFO - PROCESS | 3181 | |5;yui.html;738.8263758016866;756.5725365168679;767.6852490478901;778.844231153769;807.870740681491;16034.679306413866;16781.496555103353;16881.40915067973;17000.574985625335;17068;15889.36442542297;15951.601209969756;16381;16450.7532387014;16993.065381404016;707.9185893622248;813.1949370123594;857.0743596915347;860.1054902900976;879.6877108626442;864.9437786543886;872.3370238618663;877.6796469288728;877.9561021948876;878.2973621103092;920.3097676742443;920.8480600700876;921.0789210789211;921.4287141971987;922.2022950148107;915.729859691386;916.7983043730369;917.6054296652437;917.6421195733636;918.8759517465157;661.6625520984285;666.8032930285577;669.3306693306694;669.8526324208666;669.8526324208666;15744.606384840356;15755.2122393881;15759.369625214978;15765.763513547307;15768.369265229492;14235.43058277678;14255.572332830032;14812.481563145346;14836.406543738238;15918.283677234553;12037.699057523632;12039.63881083569;12154.817677734842;12251.264924104493;12348.38258087092;4049.372347286175;4087.8569250076403;4115.8765237042935;4142.668586513072;4191.434156388879;86592.70110948404;87104;88138.5593072037;88217.55891220503;88356;11365.261258018203;11394.544218231178;11493.827592586118;11802.11484138693;11984.101192410604;22643.88678056599;22704.432389190402;22871.19950801747;22935.19726809587;22950.885245573667;8870.112988701077;8875.911240887508;8883.42257753244;9169.724908252763;9278;4590.793414296365;4692.976535117303;4699.600533954584;4764.547368000034;5067.721275329818;3790.3556395412847;3877.9224415512094;3929.6659783918117;3933.6853051755793;3940.842366305373;678.4843518925652;685.4721864164566;686.5637484390693;687.5633972427545;694.5693669924678;636.1697984130661;656.4584218020152;675.2943174382782;685.1743648903159;688.7933619914006;9639.421634701941;9982;10016.14862736661;10241.795164096677;10267.845982310271;630.3286999345682;645.5416654175494;673.0711617967174;685.8765422224047;737.0896942276285;4534.77326133692;4805.399325084364;4814.398200224972;4849.151398505247;4916.8770780730765;126672;127798;129434;130922;136359;77209;84607;113888;116556.41721791337;118306.40846795883;98790.01209987952;99948;101543.4922825381;101573;102155.48922255341;9264.72205833826;9335.859962100438;9400.905990940004;9455.432674039579;9567.665131720356;40896.386554202305;50115.74942125266;56099;58840;59310.70344648249;80417.59791201007;88147.55926220329;89978;90363.54818225867;91467.54266228892;11162.441877906234;11433.656990290305;11540.826887596524;11575.363355015555;11687.76624467523;13497.662558436117;13532;13594.592162235149;13598.11612245201;13656.863431365558;12273.570425035081;12408.503659853866;12496.625101246977;13122;13200.471981121033;12356.62930112098;12411.44148513301;12497.37513124322;12631.747365052832;12841.165324253894;11553;12039.09706771978;12050.276983381025;12133.02935765119;12890.29103399322;9541.47521886303;9905;10068.345557538736;10077.949610251902;10199.337043092175;10225.693229203136;10659.8934010662;10853.728656783644;10865.130789536975;10901.781964360827 19:01:12 INFO - PROCESS | 3181 | __end_tp_report 19:01:12 INFO - PROCESS | 3181 | __start_cc_report 19:01:12 INFO - PROCESS | 3181 | _x_x_mozilla_cycle_collect,924 19:01:12 INFO - PROCESS | 3181 | __end_cc_report 19:01:12 INFO - PROCESS | 3181 | __startTimestamp1477360872597__endTimestamp 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | ------- Summary: start ------- 19:01:12 INFO - PROCESS | 3181 | Number of tests: 6 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#0] dojo.html Cycles:165 Average:5552.69 Median:5737.77 stddev:630.01 (11.0%) stddev-sans-first:604.23 19:01:12 INFO - PROCESS | 3181 | Values: 3196.7 3200.9 3221.7 3258.8 3271.8 5528.2 5628.8 5632.8 5633.0 5640.1 5572.9 5640.0 5644.9 5645.5 5679.5 5058.8 5105.8 5129.2 5170.9 5184.4 4993.8 5034.0 5178.9 5205.2 5205.4 5117.5 5118.7 5173.9 5220.3 5253.1 5233.5 5268.0 5275.8 5275.9 5303.4 4961.4 5089.3 5163.8 5241.4 5254.1 5416.2 5466.1 5495.8 5528.9 5594.8 5941.5 5982.8 5996.3 5997.9 6001.9 5737.8 6041.5 6048.7 6048.8 6055.2 5510.5 5514.9 5557.7 5620.0 5664.9 6115.3 6119.9 6127.4 6128.1 6132.3 5651.5 5888.9 5893.6 5938.7 5940.1 5871.0 5875.3 5934.1 5991.3 6041.3 5905.3 5937.1 5955.5 5956.2 5961.5 5822.0 5959.4 5960.0 5973.9 5979.3 4410.9 4433.5 4438.4 4451.3 4507.3 4057.5 4058.1 4059.3 4062.1 4083.6 5580.2 5582.5 5584.5 5590.5 5611.2 5773.3 5931.2 5945.7 5962.0 6087.5 5640.6 5644.5 5644.8 5670.9 5674.3 5056.4 5063.5 5069.3 5088.2 5094.9 5980.6 6035.7 6129.3 6132.7 6232.7 6029.5 6032.7 6033.1 6036.0 6056.4 6182.3 6216.9 6225.5 6235.9 6240.0 5697.7 5829.4 5847.4 5921.4 5928.1 5990.3 5999.4 6000.7 6005.2 6027.8 5861.3 5866.9 5870.9 5871.4 5874.8 5724.3 5782.2 5836.1 5837.8 5866.1 5823.7 5876.0 5877.9 5882.0 5900.3 5857.3 5904.9 5921.8 5929.1 5930.5 5544.1 5582.4 5588.2 5614.8 5772.0 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#1] ext.html Cycles:180 Average:63558.83 Median:20548.76 stddev:126451.06 (615.4%) stddev-sans-first:126717.42 19:01:12 INFO - PROCESS | 3181 | Values: 592.9 626.4 654.1 656.4 674.5 34400.0 46691.3 47483.5 47623.8 47643.8 48317.8 48636.3 48690.8 48771.3 49010.0 8512.1 9317.1 9472.0 9481.1 11072.4 10998.3 11268.0 11276.7 11278.8 11284.7 11300.4 11344.4 11345.4 11358.7 11365.4 11272.3 11321.9 11323.7 11343.0 11348.3 11079.0 11159.1 11159.6 11179.1 11180.3 45226.3 45684.3 45690.0 45765.3 45835.0 3487.2 3582.2 3611.5 3657.7 3750.5 34619.3 34660.7 34761.8 35073.0 36050.3 4652.8 4720.1 4766.4 4773.3 4780.2 356621.2 356654.0 357803.0 357830.0 358226.0 63521.4 63980.7 64004.7 64013.0 64159.0 39169.8 40307.4 40484.8 42560.8 42642.1 19509.3 19516.4 19577.3 19780.2 19783.3 13715.8 14092.6 14101.6 14166.1 14997.5 20066.0 20483.0 20780.8 20981.0 21421.4 7435.9 7583.3 7661.4 7682.4 7793.2 976.8 1040.5 1067.7 1141.8 1207.3 34578.7 34876.7 34906.0 35135.3 35178.6 1148.8 1172.8 1177.4 1207.8 1216.8 17309.0 18339.0 18384.9 18540.9 18558.9 603923.0 608475.0 609064.0 610016.0 613293.0 62001.4 62664.0 62744.0 62802.0 62824.0 1336.7 1423.1 1433.3 1454.8 1457.6 33467.0 35150.3 35918.8 35952.5 36203.8 52945.5 53130.0 53412.5 53530.7 53563.0 423473.0 427909.0 428204.0 428299.0 429801.0 27881.3 28035.4 28061.3 29165.6 29467.9 45761.0 51199.0 52139.0 52430.0 53144.7 43096.4 44667.0 44730.3 44923.8 46265.5 42713.0 42935.4 43828.6 44564.8 45309.0 43875.3 44087.8 44450.0 44676.8 45093.0 37347.0 38759.0 39093.8 39698.8 39944.4 29585.3 29590.9 29751.0 29754.6 29755.7 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#2] jquery.html Cycles:180 Average:30472.52 Median:12092.15 stddev:86908.72 (718.7%) stddev-sans-first:87123.20 19:01:12 INFO - PROCESS | 3181 | Values: 408.7 414.8 426.3 432.3 438.0 18899.7 20276.8 20807.7 21391.5 21603.8 15627.5 19484.5 20055.8 20166.2 20400.2 5352.1 5551.8 5552.4 5555.9 5557.2 5411.7 5471.1 5473.4 5694.2 5933.1 5964.1 6022.7 6029.9 6036.2 6039.0 5882.2 5989.3 5993.5 5997.6 6031.6 5805.4 6168.9 6214.5 6217.9 6224.1 13455.3 13601.4 13715.4 14360.4 14726.1 2580.5 2953.5 2959.3 3074.3 3142.1 10689.7 10788.8 10819.8 10834.4 11037.7 1949.0 1959.6 2031.3 2048.9 2071.7 57262.4 79735.0 80788.0 83106.0 88966.1 11538.0 11569.7 11633.2 11842.2 12023.8 15017.5 16086.5 16872.2 16983.2 16997.7 6848.3 6912.6 8908.3 8992.5 9318.1 8960.4 8998.5 9008.1 9237.4 9240.9 4678.5 4983.7 5001.3 5009.4 5149.9 1845.2 1974.7 1996.4 2058.9 2069.5 975.3 992.5 999.0 1052.1 1067.9 20377.8 20590.9 20620.6 20839.8 20947.4 962.2 998.4 1019.5 1027.6 1057.1 10847.4 11544.1 11579.4 11674.9 11697.0 500295.0 506877.0 506912.0 537408.0 577115.0 95702.5 98525.5 106794.0 107307.0 107570.0 1058.2 1062.9 1102.6 1106.9 1109.0 17469.0 18431.9 18476.0 19142.6 19176.0 44935.8 45283.5 45315.0 45660.1 47965.8 57976.4 63594.7 63842.0 65600.0 65871.7 8275.4 8421.9 8447.2 8882.4 9076.5 9733.9 12531.8 13395.9 13580.9 13640.5 11505.1 11648.0 11745.3 11836.2 11997.6 5500.9 10452.7 11445.7 11758.5 11806.0 12132.7 12153.5 12208.4 12223.8 12361.9 8511.8 8558.1 8667.5 8879.9 8986.9 10632.9 11345.7 11752.6 11842.0 12140.0 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#3] mootools.html Cycles:180 Average:6269.46 Median:4515.54 stddev:3571.57 (79.1%) stddev-sans-first:3562.83 19:01:12 INFO - PROCESS | 3181 | Values: 1398.8 1457.4 1586.2 1744.0 1831.2 9026.5 9731.3 9755.9 9758.0 9766.5 8424.6 9022.8 9284.0 9332.1 9491.5 2502.8 2553.3 2555.4 2556.0 2556.8 2458.9 2560.9 2561.4 2566.4 2567.4 2522.2 2528.0 2539.0 2540.5 2540.6 2348.0 2355.2 2357.5 2400.7 2406.7 2408.0 2414.4 2425.1 2425.5 2445.7 9242.4 9541.0 9651.4 9657.1 9671.8 5685.6 5839.3 5842.0 5843.3 5846.3 3584.7 3743.9 3744.6 3757.9 3814.1 1159.7 1191.6 1208.5 1209.9 1214.6 13343.1 13343.7 13356.1 13359.5 13361.2 4879.1 4956.5 4973.8 4994.7 5169.6 11191.4 11197.0 11225.6 11226.4 11233.6 5696.3 5697.7 5702.5 5702.7 5703.3 4703.2 5157.9 5204.9 5282.4 5289.5 4071.9 4102.6 4162.9 4197.2 4197.2 1326.1 1339.5 1346.4 1358.8 1366.6 907.9 942.7 1021.0 1062.1 1081.1 6473.3 6485.9 6617.0 6691.7 6825.2 1024.5 1033.4 1044.5 1044.9 1067.8 4466.9 4477.8 4493.1 4538.0 4539.6 11700.2 12017.2 12018.3 12040.6 12047.5 11007.7 11361.3 11910.3 11945.6 12037.6 8762.3 8968.6 8982.2 8982.5 8991.4 6323.5 6416.5 6448.1 6482.7 6528.8 10817.6 10960.7 11019.9 11032.1 11085.2 10875.5 11101.2 11124.8 11152.2 11294.2 7421.6 7568.5 7594.5 8143.2 8291.7 9055.5 9416.2 9454.3 9465.9 9477.1 8102.9 8130.5 8169.7 8304.8 8312.9 8113.6 8156.9 8169.8 8187.6 8338.3 7248.4 7249.3 7339.0 7342.0 7393.0 5724.6 5862.9 5881.7 5901.9 6032.7 5607.9 5740.0 5752.2 5812.2 5887.1 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#4] prototype.html Cycles:180 Average:4853.96 Median:5489.57 stddev:1428.48 (26.0%) stddev-sans-first:1391.63 19:01:12 INFO - PROCESS | 3181 | Values: 335.0 347.1 349.6 351.7 352.7 5491.2 5573.9 5591.0 5602.3 5609.6 5481.6 5487.9 5506.4 5508.2 5515.6 4143.5 4383.4 4407.0 4413.1 4422.6 3904.8 4321.0 4336.0 4340.4 4343.2 4289.6 4291.5 4314.3 4316.2 4317.1 4298.3 4310.1 4345.0 4359.1 4362.0 3636.0 3763.8 3776.3 3805.7 3870.3 5311.4 5319.3 5324.8 5356.9 5369.6 4933.2 4933.5 4935.4 4958.6 4970.4 5535.7 5784.9 5786.2 5792.8 5793.3 4202.1 4206.5 4212.7 4213.5 4214.6 5814.0 5923.2 6086.1 6087.0 6108.9 4215.7 4218.9 4225.0 4236.3 4252.1 4304.6 4313.0 4322.9 4323.5 4332.3 4823.1 4937.9 4938.6 4944.4 4972.6 5757.3 5764.8 5782.5 5794.8 5883.6 1453.2 1469.5 1476.2 1487.6 1490.3 911.5 939.6 952.0 954.0 966.7 4948.9 4967.2 4979.6 4989.7 4994.9 5216.6 5223.2 5237.4 5265.6 5275.9 4941.9 5045.2 5062.9 5284.6 5690.4 3173.5 3185.6 3187.0 3196.5 3204.7 6053.5 6158.7 6210.4 6256.8 6292.5 6069.0 6073.2 6094.2 6103.7 6108.7 6115.0 6139.2 6161.3 6212.7 6215.4 5817.5 5969.3 5970.6 5989.3 6030.5 5831.5 5894.6 5904.5 5911.9 5926.1 5942.8 5951.3 6131.7 6138.4 6181.5 4265.9 4283.0 4290.3 4310.6 4325.3 5787.6 6100.8 6106.5 6112.4 6116.2 5754.5 5814.2 5822.6 5854.5 5882.8 5824.3 5837.2 5837.6 5875.7 5892.4 5687.3 5809.4 5821.5 5844.4 5868.3 5808.4 5850.7 5855.4 5859.3 5874.7 5586.4 5737.5 5760.6 5763.5 5770.7 19:01:12 INFO - PROCESS | 3181 | 19:01:12 INFO - PROCESS | 3181 | [#5] yui.html Cycles:180 Average:22453.39 Median:4129.27 stddev:33929.66 (821.7%) stddev-sans-first:33985.67 19:01:12 INFO - PROCESS | 3181 | Values: 738.8 756.6 767.7 778.8 807.9 16034.7 16781.5 16881.4 17000.6 17068.0 15889.4 15951.6 16381.0 16450.8 16993.1 707.9 813.2 857.1 860.1 879.7 864.9 872.3 877.7 878.0 878.3 920.3 920.8 921.1 921.4 922.2 915.7 916.8 917.6 917.6 918.9 661.7 666.8 669.3 669.9 669.9 15744.6 15755.2 15759.4 15765.8 15768.4 14235.4 14255.6 14812.5 14836.4 15918.3 12037.7 12039.6 12154.8 12251.3 12348.4 4049.4 4087.9 4115.9 4142.7 4191.4 86592.7 87104.0 88138.6 88217.6 88356.0 11365.3 11394.5 11493.8 11802.1 11984.1 22643.9 22704.4 22871.2 22935.2 22950.9 8870.1 8875.9 8883.4 9169.7 9278.0 4590.8 4693.0 4699.6 4764.5 5067.7 3790.4 3877.9 3929.7 3933.7 3940.8 678.5 685.5 686.6 687.6 694.6 636.2 656.5 675.3 685.2 688.8 9639.4 9982.0 10016.1 10241.8 10267.8 630.3 645.5 673.1 685.9 737.1 4534.8 4805.4 4814.4 4849.2 4916.9 126672.0 127798.0 129434.0 130922.0 136359.0 77209.0 84607.0 113888.0 116556.4 118306.4 98790.0 99948.0 101543.5 101573.0 102155.5 9264.7 9335.9 9400.9 9455.4 9567.7 40896.4 50115.7 56099.0 58840.0 59310.7 80417.6 88147.6 89978.0 90363.5 91467.5 11162.4 11433.7 11540.8 11575.4 11687.8 13497.7 13532.0 13594.6 13598.1 13656.9 12273.6 12408.5 12496.6 13122.0 13200.5 12356.6 12411.4 12497.4 12631.7 12841.2 11553.0 12039.1 12050.3 12133.0 12890.3 9541.5 9905.0 10068.3 10077.9 10199.3 10225.7 10659.9 10853.7 10865.1 10901.8 19:01:12 INFO - PROCESS | 3181 | -------- Summary: end -------- 19:01:12 INFO - PROCESS | 3181 | 19:01:13 INFO - TEST-INFO | 3181: exit 0 19:01:13 INFO - TEST-OK | dromaeo_css | took 1112633ms 19:01:13 INFO - TEST-START | kraken 19:01:13 INFO - operating with platform_type : linux_ 19:01:13 INFO - Initialising browser for kraken test... 19:01:13 INFO - TEST-INFO | started process 3374 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS7NPhR/profile http://localhost:37425/getInfo.html) 19:01:17 INFO - PROCESS | 3374 | __metrics Screen width/height:1600/1200 19:01:17 INFO - PROCESS | 3374 | colorDepth:24 19:01:17 INFO - PROCESS | 3374 | Browser inner width/height: 1024/697 19:01:17 INFO - PROCESS | 3374 | __metrics 19:01:18 INFO - PROCESS | 3374 | 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" 19:01:18 INFO - PROCESS | 3374 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:01:19 INFO - TEST-INFO | 3374: exit 0 19:01:19 INFO - Browser initialized. 19:01:19 INFO - Running cycle 1/1 for kraken test... 19:01:19 INFO - TEST-INFO | started process 3461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpS7NPhR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:31 INFO - PROCESS | 3461 | RSS: Main: 143503360 19:01:31 INFO - PROCESS | 3461 | 19:01:33 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37425/tests/kraken/driver.html?testName=audio-beat-detection) 19:01:34 INFO - PROCESS | 3461 | RSS: Main: 199028736 19:01:34 INFO - PROCESS | 3461 | 19:01:36 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37425/tests/kraken/driver.html?testName=audio-dft) 19:01:37 INFO - PROCESS | 3461 | RSS: Main: 184438784 19:01:37 INFO - PROCESS | 3461 | 19:01:40 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37425/tests/kraken/driver.html?testName=audio-fft) 19:01:40 INFO - PROCESS | 3461 | RSS: Main: 274845696 19:01:40 INFO - PROCESS | 3461 | 19:01:42 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37425/tests/kraken/driver.html?testName=audio-oscillator) 19:01:43 INFO - PROCESS | 3461 | RSS: Main: 180715520 19:01:43 INFO - PROCESS | 3461 | 19:01:45 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37425/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:01:46 INFO - PROCESS | 3461 | RSS: Main: 179499008 19:01:46 INFO - PROCESS | 3461 | 19:01:49 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37425/tests/kraken/driver.html?testName=imaging-darkroom) 19:01:49 INFO - PROCESS | 3461 | RSS: Main: 214835200 19:01:49 INFO - PROCESS | 3461 | 19:01:53 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37425/tests/kraken/driver.html?testName=imaging-desaturate) 19:01:53 INFO - PROCESS | 3461 | RSS: Main: 213983232 19:01:53 INFO - PROCESS | 3461 | 19:01:56 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37425/tests/kraken/driver.html?testName=json-parse-financial) 19:01:57 INFO - PROCESS | 3461 | RSS: Main: 213065728 19:01:57 INFO - PROCESS | 3461 | 19:01:58 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37425/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:01:59 INFO - PROCESS | 3461 | RSS: Main: 175763456 19:01:59 INFO - PROCESS | 3461 | 19:02:00 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:02:01 INFO - PROCESS | 3461 | RSS: Main: 183119872 19:02:01 INFO - PROCESS | 3461 | 19:02:03 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:02:04 INFO - PROCESS | 3461 | RSS: Main: 189603840 19:02:04 INFO - PROCESS | 3461 | 19:02:06 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:02:07 INFO - PROCESS | 3461 | RSS: Main: 197001216 19:02:07 INFO - PROCESS | 3461 | 19:02:09 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:02:10 INFO - PROCESS | 3461 | RSS: Main: 194510848 19:02:10 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | Cycle 1(1): loaded http://localhost:37425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37425/tests/kraken/driver.html?testName=ai-astar) 19:02:12 INFO - PROCESS | 3461 | RSS: Main: 243933184 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | __start_tp_report 19:02:12 INFO - PROCESS | 3461 | _x_x_mozilla_page_load 19:02:12 INFO - PROCESS | 3461 | _x_x_mozilla_page_load_details 19:02:12 INFO - PROCESS | 3461 | |i|pagename|runs| 19:02:12 INFO - PROCESS | 3461 | |0;ai-astar;110;110;119;109;111;110;109;110;110;110 19:02:12 INFO - PROCESS | 3461 | |1;audio-beat-detection;151;115;144;185;119;145;137;116;191;115 19:02:12 INFO - PROCESS | 3461 | |2;audio-dft;160;159;159;160;158;159;160;159;159;161 19:02:12 INFO - PROCESS | 3461 | |3;audio-fft;75;75;75;170;76;75;74;74;177;76 19:02:12 INFO - PROCESS | 3461 | |4;audio-oscillator;84;83;145;84;84;83;143;82;83;148 19:02:12 INFO - PROCESS | 3461 | |5;imaging-gaussian-blur;117;117;116;116;117;117;117;117;117;117 19:02:12 INFO - PROCESS | 3461 | |6;imaging-darkroom;146;146;145;145;146;146;145;145;145;146 19:02:12 INFO - PROCESS | 3461 | |7;imaging-desaturate;94;94;95;94;94;96;96;96;94;95 19:02:12 INFO - PROCESS | 3461 | |8;json-parse-financial;49;48;51;49;49;50;50;49;49;49 19:02:12 INFO - PROCESS | 3461 | |9;json-stringify-tinderbox;60;61;60;60;61;59;60;60;61;61 19:02:12 INFO - PROCESS | 3461 | |10;stanford-crypto-aes;78;76;73;76;82;73;72;73;75;72 19:02:12 INFO - PROCESS | 3461 | |11;stanford-crypto-ccm;131;121;119;122;121;126;123;115;155;119 19:02:12 INFO - PROCESS | 3461 | |12;stanford-crypto-pbkdf2;159;154;155;149;150;155;153;154;151;154 19:02:12 INFO - PROCESS | 3461 | |13;stanford-crypto-sha256-iterative;61;61;61;61;61;62;62;63;61;62 19:02:12 INFO - PROCESS | 3461 | __end_tp_report 19:02:12 INFO - PROCESS | 3461 | __start_cc_report 19:02:12 INFO - PROCESS | 3461 | _x_x_mozilla_cycle_collect,1678 19:02:12 INFO - PROCESS | 3461 | __end_cc_report 19:02:12 INFO - PROCESS | 3461 | __startTimestamp1477360932368__endTimestamp 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | ------- Summary: start ------- 19:02:12 INFO - PROCESS | 3461 | Number of tests: 14 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.00 stddev:2.94 (2.7%) stddev-sans-first:3.10 19:02:12 INFO - PROCESS | 3461 | Values: 110.0 110.0 119.0 109.0 111.0 110.0 109.0 110.0 110.0 110.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:144.50 stddev:27.95 (19.3%) stddev-sans-first:29.45 19:02:12 INFO - PROCESS | 3461 | Values: 151.0 115.0 144.0 185.0 119.0 145.0 137.0 116.0 191.0 115.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#2] audio-dft Cycles:10 Average:159.40 Median:159.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 19:02:12 INFO - PROCESS | 3461 | Values: 160.0 159.0 159.0 160.0 158.0 159.0 160.0 159.0 159.0 161.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#3] audio-fft Cycles:10 Average:94.70 Median:75.00 stddev:41.57 (55.4%) stddev-sans-first:43.48 19:02:12 INFO - PROCESS | 3461 | Values: 75.0 75.0 75.0 170.0 76.0 75.0 74.0 74.0 177.0 76.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:83.00 stddev:30.00 (36.1%) stddev-sans-first:31.11 19:02:12 INFO - PROCESS | 3461 | Values: 84.0 83.0 145.0 84.0 84.0 83.0 143.0 82.0 83.0 148.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:02:12 INFO - PROCESS | 3461 | Values: 117.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 19:02:12 INFO - PROCESS | 3461 | Values: 146.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.92 (1.0%) stddev-sans-first:0.93 19:02:12 INFO - PROCESS | 3461 | Values: 94.0 94.0 95.0 94.0 94.0 96.0 96.0 96.0 94.0 95.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.00 stddev:0.82 (1.7%) stddev-sans-first:0.87 19:02:12 INFO - PROCESS | 3461 | Values: 49.0 48.0 51.0 49.0 49.0 50.0 50.0 49.0 49.0 49.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.50 stddev:0.67 (1.1%) stddev-sans-first:0.71 19:02:12 INFO - PROCESS | 3461 | Values: 60.0 61.0 60.0 60.0 61.0 59.0 60.0 60.0 61.0 61.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.50 stddev:3.16 (4.2%) stddev-sans-first:3.16 19:02:12 INFO - PROCESS | 3461 | Values: 78.0 76.0 73.0 76.0 82.0 73.0 72.0 73.0 75.0 72.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:122.50 stddev:11.32 (9.2%) stddev-sans-first:11.81 19:02:12 INFO - PROCESS | 3461 | Values: 131.0 121.0 119.0 122.0 121.0 126.0 123.0 115.0 155.0 119.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.00 stddev:2.88 (1.9%) stddev-sans-first:2.22 19:02:12 INFO - PROCESS | 3461 | Values: 159.0 154.0 155.0 149.0 150.0 155.0 153.0 154.0 151.0 154.0 19:02:12 INFO - PROCESS | 3461 | 19:02:12 INFO - PROCESS | 3461 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 19:02:12 INFO - PROCESS | 3461 | Values: 61.0 61.0 61.0 61.0 61.0 62.0 62.0 63.0 61.0 62.0 19:02:12 INFO - PROCESS | 3461 | -------- Summary: end -------- 19:02:12 INFO - PROCESS | 3461 | 19:02:13 INFO - TEST-INFO | 3461: exit 0 19:02:13 INFO - TEST-OK | kraken | took 59963ms 19:02:13 INFO - SUITE-END | took 1172s 19:02:13 INFO - Completed test suite (00:19:32) 19:02:13 INFO - javascript benchmark 19:02:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3196.744260459163, 3200.9199770005753, 3221.7422606191494, 3258.837058147092, 3271.787333823301, 5528.17077438384, 5628.802991895274, 5632.7746890124345, 5632.9999999999945, 5640.0693885508845, 5572.916406253899, 5639.971800141004, 5644.943550564493, 5645.491905728483, 5679.517241034517, 5058.772355244014, 5105.795768169269, 5129.153689641204, 5170.896582068356, 5184.377874655047, 4993.825216117437, 5034.043531728978, 5178.922316165242, 5205.16717325228, 5205.401378841425, 5117.46266642001, 5118.744062796864, 5173.896522069556, 5220.347456567929, 5253.106971814782, 5233.450487698794, 5268.025415298163, 5275.762590683428, 5275.86810329741, 5303.363596368422, 4961.355023846912, 5089.312942752718, 5163.767630456601, 5241.449647786965, 5254.0805359062015, 5416.187571864252, 5466.070767969415, 5495.807646732385, 5528.944710552883, 5594.832155035356, 5941.494972927307, 5982.820515384546, 5996.280446346466, 5997.910031349533, 6001.939980600182, 5737.770489180428, 6041.486473649745, 6048.727807248684, 6048.818535443944, 6055.212822333113, 5510.476504732044, 5514.91727624086, 5557.666540007613, 5619.971900140555, 5664.915026274609, 6115.327313995457, 6119.892395302511, 6127.387261273926, 6128.050152226412, 6132.325444201134, 5651.491365777099, 5888.941110588925, 5893.5579831512805, 5938.673372964528, 5940.108983652487, 5870.970645146747, 5875.265591801025, 5934.080217566284, 5991.2510936132985, 6041.2750469943885, 5905.320888097924, 5937.109433584997, 5955.464008239279, 5956.225690660273, 5961.463468287875, 5822.039363504974, 5959.404059594092, 5959.970200148971, 5973.880522389528, 5979.252593425822, 4410.867673969786, 4433.512313645497, 4438.400815889878, 4451.287793952952, 4507.301368287921, 4057.4522439470884, 4058.1072164123843, 4059.330210515291, 4062.106336605942, 4083.5916408358926, 5580.246666700024, 5582.497575218169, 5584.497395234367, 5590.524805391506, 5611.242482264924, 5773.3072031356505, 5931.228940237742, 5945.702714864239, 5961.970190149022, 6087.543434242361, 5640.633358831663, 5644.5484361251, 5644.8306550803545, 5670.886582268332, 5674.347450043298, 5056.367954005749, 5063.518965698253, 5069.340985671839, 5088.211327244283, 5094.872628184325, 5980.611260268156, 6035.7283922223605, 6129.267100814712, 6132.692058233554, 6232.719527621268, 6029.487493563096, 6032.6983650818165, 6033.064874944391, 6036.0, 6056.424639659225, 6182.258129024546, 6216.937830621817, 6225.501959843111, 6235.875282494416, 6239.96880015597, 5697.743601537858, 5829.417058294137, 5847.38602446754, 5921.437463440966, 5928.051511758098, 5990.31111422192, 5999.370066142992, 6000.669963152069, 6005.219321488178, 6027.819165425044, 5861.267341582302, 5866.853328666647, 5870.911936320873, 5871.383504731942, 5874.765009399576, 5724.255846740064, 5782.2483077199695, 5836.124581312837, 5837.766489340379, 5866.090755932837, 5823.650580965155, 5876.030454974882, 5877.882442351214, 5882.029465138205, 5900.262467191601, 5857.297124345106, 5904.911426328523, 5921.822345329647, 5929.080992446177, 5930.466258036798, 5544.0575102231915, 5582.3859375467855, 5588.245586845724, 5614.831555053355, 5772.018756811436], "value": 5510.005596536203, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.8725324055328, 626.4143026270436, 654.1300070905696, 656.3567703650423, 674.4671709349614, 34400.0, 46691.29963050553, 47483.525164748346, 47623.76188119063, 47643.76178119113, 48317.75841120789, 48636.2704559431, 48690.75654621722, 48771.26843097356, 49010.0, 8512.106228845974, 9317.114874086968, 9472.0, 9481.09929556691, 11072.446377681084, 10998.34009959401, 11268.0, 11276.718082047973, 11278.830817537744, 11284.661460156209, 11300.378479183642, 11344.376059316734, 11345.432728363548, 11358.71603209918, 11365.431728413587, 11272.267302625343, 11321.886781132165, 11323.660290191307, 11343.0, 11348.262362946423, 11079.0, 11159.051480624157, 11159.609413670483, 11179.105671546338, 11180.273282236629, 45226.32160517595, 45684.314735279, 45690.0, 45765.31352029722, 45835.0, 3487.1805125795418, 3582.247727977128, 3611.494390785292, 3657.7256705747177, 3750.5124333836698, 34619.307613847835, 34660.72505595179, 34761.82619086889, 35073.0, 36050.27899441996, 4652.790624421909, 4720.12677654635, 4766.404199475066, 4773.331733557305, 4780.163471392527, 356621.2168939165, 356654.0, 357803.0, 357830.0, 358226.0, 63521.36478635154, 63980.68009659922, 64004.67997659982, 64012.99999999907, 64159.0, 39169.804150979064, 40307.395389069185, 40484.79757601252, 42560.78719606382, 42642.14715705669, 19509.31717389913, 19516.41450756451, 19577.314793982143, 19780.208791648172, 19783.307584234484, 13715.794263086062, 14092.647683807987, 14101.576952691436, 14166.079204851652, 14997.475088371855, 20066.0, 20483.0, 20780.792192077886, 20981.0, 21421.357359279245, 7435.925640743631, 7583.317501424898, 7661.42539309554, 7682.423818213656, 7793.220677932161, 976.7802244495074, 1040.5265604150093, 1067.690369792767, 1141.785872768155, 1207.3057991654834, 34578.65421345754, 34876.65123348734, 34906.0, 35135.29729405398, 35178.64821351805, 1148.8276758486127, 1172.8240763885315, 1177.3936422742295, 1207.8188271759307, 1216.8136067334456, 17309.0, 18339.0, 18384.908075459804, 18540.907295463436, 18558.907205464155, 603923.0, 608475.0, 609064.0, 610016.0, 613293.0, 62001.37998619956, 62664.0, 62744.0, 62802.0, 62824.0, 1336.6791969927342, 1423.1390009044585, 1433.3120102350929, 1454.789055586955, 1457.5991602309239, 33467.0, 35150.29699405946, 35918.8204058978, 35952.46071308985, 36203.81898090493, 52945.470545294054, 53130.0, 53412.46587534075, 53530.732346338016, 53563.0, 423473.0, 427909.0, 428204.0, 428299.0, 429801.0, 27881.302967425978, 28035.439291213654, 28061.298467538476, 29165.56251656269, 29467.85266073656, 45761.0, 51199.0, 52139.0, 52430.0, 53144.734276328374, 43096.35355469733, 44667.0, 44730.329045065, 44923.77538112288, 46265.53734462612, 42713.0, 42935.35596965985, 43828.56171438245, 44564.77717611391, 45309.0, 43875.341869871336, 44087.77956110199, 44450.0, 44676.77661611671, 45093.0, 37347.0, 38759.0, 39093.80453097716, 39698.80150599228, 39944.40083398693, 29585.26036849096, 29590.852045739633, 29751.0, 29754.553681695226, 29755.702442975293], "value": 21694.850174222567, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.66897812771657, 414.75736694033986, 426.349816529792, 432.2975165356297, 438.04943273097376, 18899.71650425243, 20276.797232027693, 20807.687884681724, 21391.465213369673, 21603.783962160414, 15627.531174064796, 19484.51288717783, 20055.799442005613, 20166.19335226589, 20400.183992640275, 5352.116900711388, 5551.750171242284, 5552.3892371839065, 5555.916661250085, 5557.194206840006, 5411.729413529328, 5471.069918113911, 5473.425290344516, 5694.2028116063575, 5933.13969474426, 5964.075568286901, 6022.6687532185715, 6029.909551356734, 6036.185115009462, 6038.969805150968, 5882.2353094097925, 5989.251343582052, 5993.490553302974, 5997.5501837361935, 6031.577789554733, 5805.36141024487, 6168.938310616882, 6214.533909956726, 6217.875642487125, 6224.097505861625, 13455.259960702157, 13601.387937542835, 13715.382807773674, 14360.425582976668, 14726.116433014053, 2580.509703156394, 2953.4536110819354, 2959.334149816296, 3074.2621770775077, 3142.073088438916, 10689.732756681067, 10788.78422431547, 10819.78360432787, 10834.40410777407, 11037.724056898642, 1949.0, 1959.588486417854, 2031.3499680102373, 2048.8667640424806, 2071.7099606055162, 57262.42737572654, 79735.0, 80788.0, 83106.0, 88966.11033889708, 11538.0, 11569.710757230969, 11633.243839150427, 11842.230255033395, 12023.819642705366, 15017.474388396573, 16086.517404477885, 16872.156392180343, 16983.23575439108, 16997.745033824503, 6848.280930502325, 6912.619805910623, 8908.331875109392, 8992.505412202261, 9318.068193180763, 8960.417572857743, 8998.50508222041, 9008.099190080939, 9237.445753254826, 9240.8613870792, 4678.532146785295, 4983.725895075733, 5001.274815151855, 5009.373828271466, 5149.922751158736, 1845.1696736468643, 1974.7136665183468, 1996.391100714296, 2058.886761228147, 2069.4826293426645, 975.2977855943709, 992.5434300221834, 998.9900100999042, 1052.1109662335314, 1067.8718553773442, 20377.796222037887, 20590.897045514677, 20620.587588248152, 20839.79160208409, 20947.37157885266, 962.2206013129388, 998.3810037776625, 1019.5361110694616, 1027.6300531808542, 1057.101463755817, 10847.40339281347, 11544.134189935789, 11579.421028948687, 11674.94162529199, 11697.0, 500295.0, 506877.0, 506912.0, 537408.0, 577115.0, 95702.52148739212, 98525.50737246411, 106794.0, 107307.0, 107570.0, 1058.222206678091, 1062.9362238265574, 1102.6306187427301, 1106.9059129974041, 1108.9611863584735, 17469.0, 18431.907840460713, 18476.0, 19142.61714765725, 19176.0, 44935.77532112449, 45283.54716452793, 45315.0, 45660.08679826318, 47965.76017119892, 57976.4202357971, 63594.68202658957, 63842.0, 65600.0, 65871.67064164649, 8275.379346549153, 8421.87367189505, 8447.155284471504, 8882.37823352385, 9076.455412675261, 9733.853992190265, 12531.812022819482, 13395.866041339852, 13580.86419135796, 13640.454381824617, 11505.079593632321, 11648.0, 11745.295282282748, 11836.230645008047, 11997.64007079789, 5500.862478438071, 10452.738681532719, 11445.656630301104, 11758.529658813552, 11806.0, 12132.696682583006, 12153.513859445524, 12208.389580520761, 12223.75552488963, 12361.93819030899, 8511.829763404821, 8558.101399353227, 8667.479951202948, 8879.866801997847, 8986.86519702218, 10632.946835265775, 11345.659630211107, 11752.58865939688, 11842.0, 12140.0], "value": 9468.444414489151, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.7831886057659, 1457.4170331867251, 1586.159335552157, 1743.9710570763248, 1831.2400353853152, 9026.50354230517, 9731.270154738406, 9755.902440975588, 9757.951210243938, 9766.462844543546, 8424.620892059856, 9022.819543609125, 9284.025177356381, 9332.113449222314, 9491.477968711719, 2502.812289078322, 2553.259554729126, 2555.399481121935, 2555.9744402556016, 2556.7954563634867, 2458.889349979246, 2560.9231723048338, 2561.4108754986364, 2566.4097257630756, 2567.3838278813046, 2522.2181123851665, 2527.9620805687837, 2538.987305063472, 2540.4665020345656, 2540.5554028045112, 2347.9882600587057, 2355.199232261022, 2357.5167090746354, 2400.7119145702454, 2406.7111946566347, 2407.9759202407927, 2414.420539070628, 2425.1027119965547, 2425.466397392571, 2445.6698345723457, 9242.353035287533, 9540.95229523848, 9651.372660777097, 9657.082577155159, 9671.758206044835, 5685.602007859451, 5839.270091238595, 5842.006858834008, 5843.2695913010875, 5846.298444186682, 3584.713222942158, 3743.906402339936, 3744.588095309514, 3757.9436308455397, 3814.084619691254, 1159.7069267766465, 1191.559123124441, 1208.546794951893, 1209.897158741508, 1214.5688280660415, 13343.132696374705, 13343.733125337536, 13356.131851429625, 13359.465621375133, 13361.198328100249, 4879.121758083508, 4956.4547899731, 4973.751312434364, 4994.675346102565, 5169.638125331266, 11191.384473854016, 11197.0, 11225.60710375133, 11226.382548959886, 11233.606823761293, 5696.259486266758, 5697.686627235459, 5702.515286200636, 5702.714864256771, 5703.258576385043, 4703.223968045234, 5157.922631160535, 5204.947950520446, 5282.366116066097, 5289.497497737709, 4071.898202544901, 4102.589741025933, 4162.895927601834, 4197.160567886435, 4197.202531519001, 1326.065124087516, 1339.450825161679, 1346.414309775251, 1358.7825947848296, 1366.6173471428017, 907.8502047162276, 942.6559305853314, 1020.9591616335264, 1062.081299675775, 1081.0756802933536, 6473.28793832678, 6485.935140648533, 6616.966915165393, 6691.698873550702, 6825.180978282638, 1024.4775164665925, 1033.395463653756, 1044.4568824211367, 1044.8537204791335, 1067.8451624514403, 4466.865994020184, 4477.843275485342, 4493.078918821634, 4538.0, 4539.6368290536675, 11700.239484433485, 12017.218880772722, 12018.33899135556, 12040.578579749666, 12047.457864396123, 11007.669769906915, 11361.318320900773, 11910.344931028876, 11945.641630751092, 12037.638870833889, 8762.255208307237, 8968.596413161424, 8982.191602755784, 8982.505962172012, 8991.370604057784, 6323.494120470444, 6416.48668106541, 6448.065030570448, 6482.740690372332, 6528.836779080371, 10817.56729730802, 10960.725981850199, 11019.944900275768, 11032.062274706419, 11085.22403431752, 10875.456227188768, 11101.222914395914, 11124.833127502932, 11152.16358773079, 11294.152938529478, 7421.591812450367, 7568.5458872467825, 7594.5063570867715, 8143.18568143181, 8291.668333266602, 9055.547222638974, 9416.152546270869, 9454.33819632619, 9465.905340946778, 9477.052294770469, 8102.878456823035, 8130.471519351223, 8169.714060008109, 8304.750857474286, 8312.916870831456, 8113.634886430007, 8156.877646835183, 8169.79575510617, 8187.590620468834, 8338.332933365195, 7248.42012638977, 7249.275072492708, 7339.0, 7342.045534080536, 7392.96303518479, 5724.599278050497, 5862.94137058624, 5881.705914704162, 5901.852453688693, 6032.668203248863, 5607.943920560742, 5740.0, 5752.223449834219, 5812.157237200667, 5887.146363777143], "value": 5003.6913032016055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.98492567834444, 347.0715835140997, 349.5805033959249, 351.71335361680246, 352.74426041120194, 5491.23122762813, 5573.888522229553, 5590.972045139779, 5602.32772067352, 5609.607327487068, 5481.588880833933, 5487.917681234775, 5506.449355064491, 5508.228847961289, 5515.641483303582, 4143.482064741907, 4383.3863259143745, 4406.97796511017, 4413.1173765246995, 4422.579854913778, 3904.7657140571514, 4320.97839510802, 4335.9566404335865, 4340.370646256291, 4343.153085148398, 4289.571042895701, 4291.527931927501, 4314.331278651814, 4316.223079845627, 4317.13657268546, 4298.290782020963, 4310.137972405531, 4344.978275108636, 4359.149965756678, 4361.978190109061, 3636.0, 3763.7553559018575, 3776.2636285924254, 3805.714571407128, 3870.2646497165447, 5311.389190243094, 5319.335083114611, 5324.787008519655, 5356.866078348034, 5369.62412631116, 4933.199382225498, 4933.481984391659, 4935.35840340757, 4958.628102892261, 4970.403551573798, 5535.667859928417, 5784.88430231393, 5786.247787787603, 5792.8262152135085, 5793.275840519935, 4202.138561594866, 4206.453161089069, 4212.705110642256, 4213.53651098382, 4214.557471465482, 5813.970930145365, 5923.170756094193, 6086.056661217518, 6086.969565152191, 6108.877822443526, 4215.747055176638, 4218.915621687549, 4225.0, 4236.322188449833, 4252.149570085995, 4304.569543045733, 4312.956870431318, 4322.935155972663, 4323.524412314643, 4332.263515202423, 4823.083614113307, 4937.876553086202, 4938.629602779806, 4944.3572335596145, 4972.57733092684, 5757.280339957505, 5764.827055188351, 5782.537397008229, 5794.797976768885, 5883.558733094949, 1453.2080016391092, 1469.4930249064055, 1476.1733429279639, 1487.605784467117, 1490.2921112471706, 911.470668893825, 939.5537119868145, 952.0051546134306, 953.9713808585753, 966.6906589891167, 4948.925766113512, 4967.230079337708, 4979.634673530117, 4989.727619457088, 4994.902141100746, 5216.634835561475, 5223.242629818655, 5237.397699264558, 5265.578753699695, 5275.944811037808, 4941.888075183127, 5045.167547354646, 5062.873428164326, 5284.5772338212855, 5690.374058853606, 3173.4763763979145, 3185.61772587291, 3187.0, 3196.5045417960255, 3204.7275981541366, 6053.545984051126, 6158.692065396802, 6210.441060304594, 6256.812295631138, 6292.46514046302, 6069.0, 6073.210482637317, 6094.177286066413, 6103.694815259308, 6108.725107370179, 6114.960456722368, 6139.2325959255095, 6161.291451483047, 6212.658303793335, 6215.378462153839, 5817.505512031525, 5969.283685957713, 5970.567063904718, 5989.251343582052, 6030.48740857032, 5831.475167234969, 5894.587378883609, 5904.468597826216, 5911.881762364643, 5926.140709597171, 5942.821715348374, 5951.285845698544, 6131.662758548323, 6138.447539721446, 6181.474574661204, 4265.850695225652, 4282.972086699107, 4290.292101803292, 4310.555963752178, 4325.307950727868, 5787.594868359174, 6100.786472473442, 6106.45041946227, 6112.449879510687, 6116.17431646722, 5754.503829004572, 5814.244148260699, 5822.5633077518505, 5854.4730974212525, 5882.794102206402, 5824.271966004249, 5837.241158649348, 5837.620554663933, 5875.706214689334, 5892.410758924073, 5687.345955215038, 5809.419058094157, 5821.505172060422, 5844.415558444121, 5868.2958045033165, 5808.448197421239, 5850.678212698343, 5855.38518455556, 5859.326177489473, 5874.676892770939, 5586.357568879631, 5737.512311453406, 5760.596758226884, 5763.452472015152, 5770.682612456355], "value": 4397.391068543454, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.8263758016866, 756.5725365168679, 767.6852490478901, 778.844231153769, 807.870740681491, 16034.679306413866, 16781.496555103353, 16881.40915067973, 17000.574985625335, 17068.0, 15889.36442542297, 15951.601209969756, 16381.0, 16450.7532387014, 16993.065381404016, 707.9185893622248, 813.1949370123594, 857.0743596915347, 860.1054902900976, 879.6877108626442, 864.9437786543886, 872.3370238618663, 877.6796469288728, 877.9561021948876, 878.2973621103092, 920.3097676742443, 920.8480600700876, 921.0789210789211, 921.4287141971987, 922.2022950148107, 915.729859691386, 916.7983043730369, 917.6054296652437, 917.6421195733636, 918.8759517465157, 661.6625520984285, 666.8032930285577, 669.3306693306694, 669.8526324208666, 669.8526324208666, 15744.606384840356, 15755.2122393881, 15759.369625214978, 15765.763513547307, 15768.369265229492, 14235.43058277678, 14255.572332830032, 14812.481563145346, 14836.406543738238, 15918.283677234553, 12037.699057523632, 12039.63881083569, 12154.817677734842, 12251.264924104493, 12348.38258087092, 4049.372347286175, 4087.8569250076403, 4115.8765237042935, 4142.668586513072, 4191.434156388879, 86592.70110948404, 87104.0, 88138.5593072037, 88217.55891220503, 88356.0, 11365.261258018203, 11394.544218231178, 11493.827592586118, 11802.11484138693, 11984.101192410604, 22643.88678056599, 22704.432389190402, 22871.19950801747, 22935.19726809587, 22950.885245573667, 8870.112988701077, 8875.911240887508, 8883.42257753244, 9169.724908252763, 9278.0, 4590.793414296365, 4692.976535117303, 4699.600533954584, 4764.547368000034, 5067.721275329818, 3790.3556395412847, 3877.9224415512094, 3929.6659783918117, 3933.6853051755793, 3940.842366305373, 678.4843518925652, 685.4721864164566, 686.5637484390693, 687.5633972427545, 694.5693669924678, 636.1697984130661, 656.4584218020152, 675.2943174382782, 685.1743648903159, 688.7933619914006, 9639.421634701941, 9982.0, 10016.14862736661, 10241.795164096677, 10267.845982310271, 630.3286999345682, 645.5416654175494, 673.0711617967174, 685.8765422224047, 737.0896942276285, 4534.77326133692, 4805.399325084364, 4814.398200224972, 4849.151398505247, 4916.8770780730765, 126672.0, 127798.0, 129434.0, 130922.0, 136359.0, 77209.0, 84607.0, 113888.0, 116556.41721791337, 118306.40846795883, 98790.01209987952, 99948.0, 101543.4922825381, 101573.0, 102155.48922255341, 9264.72205833826, 9335.859962100438, 9400.905990940004, 9455.432674039579, 9567.665131720356, 40896.386554202305, 50115.74942125266, 56099.0, 58840.0, 59310.70344648249, 80417.59791201007, 88147.55926220329, 89978.0, 90363.54818225867, 91467.54266228892, 11162.441877906234, 11433.656990290305, 11540.826887596524, 11575.363355015555, 11687.76624467523, 13497.662558436117, 13532.0, 13594.592162235149, 13598.11612245201, 13656.863431365558, 12273.570425035081, 12408.503659853866, 12496.625101246977, 13122.0, 13200.471981121033, 12356.62930112098, 12411.44148513301, 12497.37513124322, 12631.747365052832, 12841.165324253894, 11553.0, 12039.09706771978, 12050.276983381025, 12133.02935765119, 12890.29103399322, 9541.47521886303, 9905.0, 10068.345557538736, 10077.949610251902, 10199.337043092175, 10225.693229203136, 10659.8934010662, 10853.728656783644, 10865.130789536975, 10901.781964360827], "value": 7763.568979359259, "unit": "score"}], "name": "dromaeo_css", "value": 7604.365413374245, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 119.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 144.0, 185.0, 119.0, 145.0, 137.0, 116.0, 191.0, 115.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 159.0, 160.0, 158.0, 159.0, 160.0, 159.0, 159.0, 161.0], "value": 159.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 170.0, 76.0, 75.0, 74.0, 74.0, 177.0, 76.0], "value": 94.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 145.0, 84.0, 84.0, 83.0, 143.0, 82.0, 83.0, 148.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 96.0, 96.0, 96.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 51.0, 49.0, 49.0, 50.0, 50.0, 49.0, 49.0, 49.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 59.0, 60.0, 60.0, 61.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 73.0, 76.0, 82.0, 73.0, 72.0, 73.0, 75.0, 72.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 121.0, 119.0, 122.0, 121.0, 126.0, 123.0, 115.0, 155.0, 119.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 155.0, 149.0, 150.0, 155.0, 153.0, 154.0, 151.0, 154.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 63.0, 61.0, 62.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1490.4, "alertThreshold": 2.0}]} 19:02:13 INFO - Return code: 0 19:02:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:02:13 INFO - # TBPL SUCCESS # 19:02:13 INFO - Running post-action listener: _resource_record_post_action 19:02:13 INFO - [mozharness: 2016-10-25 02:02:13.677716Z] Finished run-tests step (success) 19:02:13 INFO - Running post-run listener: _resource_record_post_run 19:02:13 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 288600064; Read time: 0; Write time: 161140 19:02:13 INFO - TinderboxPrint: CPU usage
12.9% 19:02:13 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:02:13 INFO - TinderboxPrint: I/O write bytes / time
288,600,064 / 161,140 19:02:13 INFO - TinderboxPrint: CPU idle
8,251.3 (87.1%) 19:02:13 INFO - TinderboxPrint: CPU user
1,181.8 (12.5%) 19:02:13 INFO - TinderboxPrint: Swap in / out
0 / 0 19:02:13 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70115328; Read time: 0; Write time: 40392 19:02:13 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 218484736; Read time: 0; Write time: 120748 19:02:14 INFO - Running post-run listener: _upload_blobber_files 19:02:14 INFO - Blob upload gear active. 19:02:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:02:14 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 19:02:14 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'] 19:02:14 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 19:02:14 INFO - (blobuploader) - INFO - Open directory for files ... 19:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:16 INFO - (blobuploader) - INFO - Done attempting. 19:02:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:02:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:02:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:17 INFO - (blobuploader) - INFO - Done attempting. 19:02:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:02:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:02:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:20 INFO - (blobuploader) - INFO - Done attempting. 19:02:20 INFO - (blobuploader) - INFO - Iteration through files over. 19:02:20 INFO - Return code: 0 19:02:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:02:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:02:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b58f9de5de31e431e39d33f0b92e2ae52b2846e9def675220f231658afe7a22ffb98705d93655d1e5af843b0e13c13eadd68e620b9348f0a554fbda05b566d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b1c81d44a82c84eafbf01722fe4ac8b532a0566abc6f7a03517cea51dd324ed01d2d65b2bb3332c7656b6c8e476bae880d33a401e50450f130235ce944cd127", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/55af4f282f24a1c07153e95718464b2980dc17413ee6fc905dd09062beb6870f970f5a27f817415d90267bc94a657d0ebe798a6c208c04bf5c1893e52fc8a5cd"} 19:02:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:02:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:02:20 INFO - Contents: 19:02:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b58f9de5de31e431e39d33f0b92e2ae52b2846e9def675220f231658afe7a22ffb98705d93655d1e5af843b0e13c13eadd68e620b9348f0a554fbda05b566d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b1c81d44a82c84eafbf01722fe4ac8b532a0566abc6f7a03517cea51dd324ed01d2d65b2bb3332c7656b6c8e476bae880d33a401e50450f130235ce944cd127", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/55af4f282f24a1c07153e95718464b2980dc17413ee6fc905dd09062beb6870f970f5a27f817415d90267bc94a657d0ebe798a6c208c04bf5c1893e52fc8a5cd"} 19:02:20 INFO - Running post-run listener: copy_logs_to_upload_dir 19:02:20 INFO - Copying logs to upload dir... 19:02:20 INFO - mkdir: /builds/slave/test/build/upload/logs 19:02:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1675.149188 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 55 secs) (at 2016-10-24 19:02:20.142398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:02:20.145785) ========= 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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 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/b58f9de5de31e431e39d33f0b92e2ae52b2846e9def675220f231658afe7a22ffb98705d93655d1e5af843b0e13c13eadd68e620b9348f0a554fbda05b566d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b1c81d44a82c84eafbf01722fe4ac8b532a0566abc6f7a03517cea51dd324ed01d2d65b2bb3332c7656b6c8e476bae880d33a401e50450f130235ce944cd127", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/55af4f282f24a1c07153e95718464b2980dc17413ee6fc905dd09062beb6870f970f5a27f817415d90267bc94a657d0ebe798a6c208c04bf5c1893e52fc8a5cd"} build_url:https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/RW4qZuJ7SPevpv1B4YnHHg/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/b58f9de5de31e431e39d33f0b92e2ae52b2846e9def675220f231658afe7a22ffb98705d93655d1e5af843b0e13c13eadd68e620b9348f0a554fbda05b566d23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3b1c81d44a82c84eafbf01722fe4ac8b532a0566abc6f7a03517cea51dd324ed01d2d65b2bb3332c7656b6c8e476bae880d33a401e50450f130235ce944cd127", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/55af4f282f24a1c07153e95718464b2980dc17413ee6fc905dd09062beb6870f970f5a27f817415d90267bc94a657d0ebe798a6c208c04bf5c1893e52fc8a5cd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:02:20.171135) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:02:20.171499) ========= 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=db7e7c15f3ca076d53731445000001ba-1477359263.46629-1492743177 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:02:20.255694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:02:20.256216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:02:20.256748) ========= ========= Total master_lag: 0.65 =========