builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-031 starttime: 1471053703.42 results: success (0) buildid: 20160812154615 builduid: c21ebdbf17bf4120ae93ccef2bf2292d revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.419086) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.419596) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.419925) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.436779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.437434) ========= 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=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-12 19:01:43-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.7M=0s 2016-08-12 19:01:43 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094897 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.546183) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.546520) ========= 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=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023819 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:43.596001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 19:01:43.596354) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-12 19:01:43,850 truncating revision to first 12 chars 2016-08-12 19:01:43,850 Setting DEBUG logging. 2016-08-12 19:01:43,850 attempt 1/10 2016-08-12 19:01:43,850 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a6fcbbeaddff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 19:01:44,871 unpacking tar archive at: mozilla-aurora-a6fcbbeaddff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.397830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 19:01:45.009331) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:45.009647) ========= script_repo_revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:45.010069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:45.010349) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 19:01:45.020817) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-12 19:01:45.021084) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:01:45 INFO - MultiFileLogger online at 20160812 19:01:45 in /builds/slave/test 19:01:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:01:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:01:45 INFO - {'append_to_log': False, 19:01:45 INFO - 'base_work_dir': '/builds/slave/test', 19:01:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:01:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:01:45 INFO - 'branch': 'Mozilla-Aurora', 19:01:45 INFO - 'buildbot_json_path': 'buildprops.json', 19:01:45 INFO - 'config_files': ('talos/linux_config.py',), 19:01:45 INFO - 'default_actions': ('clobber', 19:01:45 INFO - 'read-buildbot-config', 19:01:45 INFO - 'download-and-extract', 19:01:45 INFO - 'populate-webroot', 19:01:45 INFO - 'create-virtualenv', 19:01:45 INFO - 'install', 19:01:45 INFO - 'run-tests'), 19:01:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:01:45 INFO - 'download_minidump_stackwalk': True, 19:01:45 INFO - 'download_symbols': 'ondemand', 19:01:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:01:45 INFO - 'tooltool.py': '/tools/tooltool.py', 19:01:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:01:45 INFO - '/tools/misc-python/virtualenv.py')}, 19:01:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:01:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:01:45 INFO - 'installer_path': 'installer.exe', 19:01:45 INFO - 'log_level': 'info', 19:01:45 INFO - 'log_name': 'talos', 19:01:45 INFO - 'log_to_console': True, 19:01:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:01:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:01:45 INFO - 'opt_config_files': (), 19:01:45 INFO - 'pip_index': False, 19:01:45 INFO - 'sps_profile': False, 19:01:45 INFO - 'sps_profile_interval': 0, 19:01:45 INFO - 'suite': 'dromaeojs-e10s', 19:01:45 INFO - 'system_bits': '32', 19:01:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:01:45 INFO - 'title': 'talos-linux64-ix-031', 19:01:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:01:45 INFO - 'use_talos_json': True, 19:01:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:01:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:01:45 INFO - 'work_dir': 'build'} 19:01:45 INFO - [mozharness: 2016-08-13 02:01:45.364901Z] Running clobber step. 19:01:45 INFO - Running pre-action listener: _resource_record_pre_action 19:01:45 INFO - Running main action method: clobber 19:01:45 INFO - rmtree: /builds/slave/test/build 19:01:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:01:45 INFO - Running post-action listener: _resource_record_post_action 19:01:45 INFO - [mozharness: 2016-08-13 02:01:45.818624Z] Finished clobber step (success) 19:01:45 INFO - [mozharness: 2016-08-13 02:01:45.818724Z] Running read-buildbot-config step. 19:01:45 INFO - Running pre-action listener: _resource_record_pre_action 19:01:45 INFO - Running main action method: read_buildbot_config 19:01:45 INFO - Using buildbot properties: 19:01:45 INFO - { 19:01:45 INFO - "project": "", 19:01:45 INFO - "product": "firefox", 19:01:45 INFO - "script_repo_revision": "production", 19:01:45 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 19:01:45 INFO - "repository": "", 19:01:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 19:01:45 INFO - "buildid": "20160812154615", 19:01:45 INFO - "pgo_build": "True", 19:01:45 INFO - "basedir": "/builds/slave/test", 19:01:45 INFO - "buildnumber": 458, 19:01:45 INFO - "slavename": "talos-linux64-ix-031", 19:01:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:01:45 INFO - "platform": "ubuntu64_hw", 19:01:45 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:01:45 INFO - "branch": "mozilla-aurora", 19:01:45 INFO - "repo_path": "releases/mozilla-aurora", 19:01:45 INFO - "revision": "a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5", 19:01:45 INFO - "stage_platform": "linux64-pgo", 19:01:45 INFO - "builduid": "c21ebdbf17bf4120ae93ccef2bf2292d", 19:01:45 INFO - "slavebuilddir": "test" 19:01:45 INFO - } 19:01:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 19:01:45 INFO - Running post-action listener: _resource_record_post_action 19:01:45 INFO - [mozharness: 2016-08-13 02:01:45.821400Z] Finished read-buildbot-config step (success) 19:01:45 INFO - [mozharness: 2016-08-13 02:01:45.821498Z] Running download-and-extract step. 19:01:45 INFO - Running pre-action listener: _resource_record_pre_action 19:01:45 INFO - Running main action method: download_and_extract 19:01:45 INFO - mkdir: /builds/slave/test/build/tests 19:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:01:45 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:01:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:01:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:01:45 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:01:45 INFO - retry: Failed, sleeping 30 seconds before retrying 19:02:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 19:02:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:02:15 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:02:15 INFO - retry: Failed, sleeping 60 seconds before retrying 19:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 19:03:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:15 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:03:15 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 19:03:15 INFO - Caught exception: HTTP Error 404: Not Found 19:03:15 INFO - Caught exception: HTTP Error 404: Not Found 19:03:15 INFO - Caught exception: HTTP Error 404: Not Found 19:03:15 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:15 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:03:16 INFO - Downloaded 1361 bytes. 19:03:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 19:03:16 INFO - Using the following test package requirements: 19:03:16 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:03:16 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:03:16 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:03:16 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'jsshell-linux-x86_64.zip'], 19:03:16 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:03:16 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 19:03:16 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:03:16 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:03:16 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:03:16 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 19:03:16 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:03:16 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:16 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:03:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:03:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:16 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:03:16 INFO - retry: Failed, sleeping 30 seconds before retrying 19:03:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 19:03:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:03:46 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:03:46 INFO - retry: Failed, sleeping 60 seconds before retrying 19:04:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 19:04:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:46 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:04:46 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 19:04:46 INFO - Caught exception: HTTP Error 404: Not Found 19:04:46 INFO - Caught exception: HTTP Error 404: Not Found 19:04:46 INFO - Caught exception: HTTP Error 404: Not Found 19:04:46 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:46 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:04:47 INFO - Downloaded 21765597 bytes. 19:04:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:04:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:04:48 INFO - Return code: 0 19:04:48 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:04:48 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')]} 19:04:48 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:04:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:04:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:04:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:04:48 INFO - retry: Failed, sleeping 30 seconds before retrying 19:05:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 19:05:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:05:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:05:18 INFO - retry: Failed, sleeping 60 seconds before retrying 19:06:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 19:06:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:06:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:06:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 19:06:18 INFO - Caught exception: HTTP Error 404: Not Found 19:06:18 INFO - Caught exception: HTTP Error 404: Not Found 19:06:18 INFO - Caught exception: HTTP Error 404: Not Found 19:06:18 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:06:18 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 19:06:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:06:19 INFO - Downloaded 21765597 bytes. 19:06:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:06:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 19:06:20 INFO - Return code: 0 19:06:20 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')]} 19:06:20 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:06:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:06:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:06:20 INFO - retry: Failed, sleeping 30 seconds before retrying 19:06:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 19:06:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:06:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:06:50 INFO - retry: Failed, sleeping 60 seconds before retrying 19:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 19:07:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:07:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:07:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 19:07:51 INFO - Caught exception: HTTP Error 404: Not Found 19:07:51 INFO - Caught exception: HTTP Error 404: Not Found 19:07:51 INFO - Caught exception: HTTP Error 404: Not Found 19:07:51 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:07:51 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 19:07:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:07:52 INFO - Downloaded 14413723 bytes. 19:07:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:07:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 19:07:52 INFO - Return code: 0 19:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:52 INFO - https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:07:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:07:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:07:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:07:52 INFO - retry: Failed, sleeping 30 seconds before retrying 19:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 19:08:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:08:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:08:22 INFO - retry: Failed, sleeping 60 seconds before retrying 19:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 19:09:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:09:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 19:09:22 INFO - Caught exception: HTTP Error 404: Not Found 19:09:22 INFO - Caught exception: HTTP Error 404: Not Found 19:09:22 INFO - Caught exception: HTTP Error 404: Not Found 19:09:22 INFO - trying https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:22 INFO - Downloading https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:09:23 INFO - Downloaded 63764023 bytes. 19:09:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:23 INFO - mkdir: /builds/slave/test/properties 19:09:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:09:23 INFO - Writing to file /builds/slave/test/properties/build_url 19:09:23 INFO - Contents: 19:09:23 INFO - build_url:https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 19:09:24 INFO - Running post-action listener: _resource_record_post_action 19:09:24 INFO - Running post-action listener: set_extra_try_arguments 19:09:24 INFO - [mozharness: 2016-08-13 02:09:24.437894Z] Finished download-and-extract step (success) 19:09:24 INFO - [mozharness: 2016-08-13 02:09:24.438127Z] Running populate-webroot step. 19:09:24 INFO - Running pre-action listener: _resource_record_pre_action 19:09:24 INFO - Running main action method: populate_webroot 19:09:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:24 INFO - '--tppagecycles', 19:09:24 INFO - '7'], 19:09:24 INFO - 'tests': ['tsvgm']}}, 19:09:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:09:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:09:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:09:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['damp', 'tps']}, 19:09:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'tests': ['damp', 'tps']}, 19:09:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['dromaeo_dom']}, 19:09:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['basic_compositor_video']}, 19:09:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:09:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'other-e10s': {'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['a11yr', 19:09:24 INFO - 'ts_paint', 19:09:24 INFO - 'tpaint', 19:09:24 INFO - 'sessionrestore', 19:09:24 INFO - 'sessionrestore_no_auto_restore', 19:09:24 INFO - 'tabpaint']}, 19:09:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['tsvgx', 19:09:24 INFO - 'tsvgr_opacity', 19:09:24 INFO - 'tart', 19:09:24 INFO - 'tscrollx', 19:09:24 INFO - 'cart']}, 19:09:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:09:24 INFO - 'tsvgr_opacity', 19:09:24 INFO - 'tart', 19:09:24 INFO - 'tscrollx', 19:09:24 INFO - 'cart']}, 19:09:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'talos_options': ['--disable-e10s'], 19:09:24 INFO - 'tests': ['tp5o']}, 19:09:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'tests': ['tp5o']}, 19:09:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'talos_options': ['--disable-e10s', 19:09:24 INFO - '--xperf_path', 19:09:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:24 INFO - 'tests': ['tp5n']}, 19:09:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:24 INFO - 'talos_options': ['--xperf_path', 19:09:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:24 INFO - 'tests': ['tp5n']}}, 19:09:24 INFO - 'talos.zip': {'path': '', 19:09:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:24 INFO - Running post-action listener: _resource_record_post_action 19:09:24 INFO - [mozharness: 2016-08-13 02:09:24.458531Z] Finished populate-webroot step (success) 19:09:24 INFO - [mozharness: 2016-08-13 02:09:24.458621Z] Running create-virtualenv step. 19:09:24 INFO - Running pre-action listener: _resource_record_pre_action 19:09:24 INFO - Running main action method: create_virtualenv 19:09:24 INFO - Creating virtualenv /builds/slave/test/build/venv 19:09:24 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 19:09:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:09:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:24 INFO - Using real prefix '/usr' 19:09:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:09:25 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:27 INFO - Installing pip.................done. 19:09:27 INFO - Return code: 0 19:09:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:27 INFO - Using env: {'DISPLAY': ':0', 19:09:27 INFO - 'HOME': '/home/cltbld', 19:09:27 INFO - 'LANG': 'en_US.UTF-8', 19:09:27 INFO - 'LANGUAGE': 'en_US:en', 19:09:27 INFO - 'LOGNAME': 'cltbld', 19:09:27 INFO - 'MAIL': '/var/mail/cltbld', 19:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:27 INFO - 'MOZ_NO_REMOTE': '1', 19:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:27 INFO - 'NO_EM_RESTART': '1', 19:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:27 INFO - 'PWD': '/builds/slave/test', 19:09:27 INFO - 'SHELL': '/bin/bash', 19:09:27 INFO - 'SHLVL': '1', 19:09:27 INFO - 'TERM': 'linux', 19:09:27 INFO - 'TMOUT': '86400', 19:09:27 INFO - 'USER': 'cltbld', 19:09:27 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:27 INFO - '_': '/tools/buildbot/bin/python'} 19:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:27 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 19:09:27 INFO - Cleaning up... 19:09:27 INFO - Return code: 0 19:09:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:09:27 INFO - Using env: {'DISPLAY': ':0', 19:09:27 INFO - 'HOME': '/home/cltbld', 19:09:27 INFO - 'LANG': 'en_US.UTF-8', 19:09:27 INFO - 'LANGUAGE': 'en_US:en', 19:09:27 INFO - 'LOGNAME': 'cltbld', 19:09:27 INFO - 'MAIL': '/var/mail/cltbld', 19:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:27 INFO - 'MOZ_NO_REMOTE': '1', 19:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:27 INFO - 'NO_EM_RESTART': '1', 19:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:27 INFO - 'PWD': '/builds/slave/test', 19:09:27 INFO - 'SHELL': '/bin/bash', 19:09:27 INFO - 'SHLVL': '1', 19:09:27 INFO - 'TERM': 'linux', 19:09:27 INFO - 'TMOUT': '86400', 19:09:27 INFO - 'USER': 'cltbld', 19:09:27 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:27 INFO - '_': '/tools/buildbot/bin/python'} 19:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:27 INFO - Downloading/unpacking psutil>=3.1.1 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:29 INFO - Installing collected packages: psutil 19:09:29 INFO - Running setup.py install for psutil 19:09:29 INFO - building 'psutil._psutil_linux' extension 19:09:29 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 19:09:29 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 19:09:29 INFO - building 'psutil._psutil_posix' extension 19:09:29 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 19:09:29 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 19:09:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:29 INFO - Successfully installed psutil 19:09:29 INFO - Cleaning up... 19:09:29 INFO - Return code: 0 19:09:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:09:29 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')]} 19:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 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')]} 19:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:29 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:29 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 19:09:29 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 19:09:29 INFO - Using env: {'DISPLAY': ':0', 19:09:29 INFO - 'HOME': '/home/cltbld', 19:09:29 INFO - 'LANG': 'en_US.UTF-8', 19:09:29 INFO - 'LANGUAGE': 'en_US:en', 19:09:29 INFO - 'LOGNAME': 'cltbld', 19:09:29 INFO - 'MAIL': '/var/mail/cltbld', 19:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:29 INFO - 'MOZ_NO_REMOTE': '1', 19:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:29 INFO - 'NO_EM_RESTART': '1', 19:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:29 INFO - 'PWD': '/builds/slave/test', 19:09:29 INFO - 'SHELL': '/bin/bash', 19:09:29 INFO - 'SHLVL': '1', 19:09:29 INFO - 'TERM': 'linux', 19:09:29 INFO - 'TMOUT': '86400', 19:09:29 INFO - 'USER': 'cltbld', 19:09:29 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:29 INFO - '_': '/tools/buildbot/bin/python'} 19:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:09:32 INFO - Installing collected packages: mozsystemmonitor 19:09:32 INFO - Running setup.py install for mozsystemmonitor 19:09:32 INFO - Successfully installed mozsystemmonitor 19:09:32 INFO - Cleaning up... 19:09:32 INFO - Return code: 0 19:09:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:09: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')]} 19:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:32 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:32 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 19:09:32 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 19:09:32 INFO - Using env: {'DISPLAY': ':0', 19:09:32 INFO - 'HOME': '/home/cltbld', 19:09:32 INFO - 'LANG': 'en_US.UTF-8', 19:09:32 INFO - 'LANGUAGE': 'en_US:en', 19:09:32 INFO - 'LOGNAME': 'cltbld', 19:09:32 INFO - 'MAIL': '/var/mail/cltbld', 19:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:32 INFO - 'MOZ_NO_REMOTE': '1', 19:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:32 INFO - 'NO_EM_RESTART': '1', 19:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:32 INFO - 'PWD': '/builds/slave/test', 19:09:32 INFO - 'SHELL': '/bin/bash', 19:09:32 INFO - 'SHLVL': '1', 19:09:32 INFO - 'TERM': 'linux', 19:09:32 INFO - 'TMOUT': '86400', 19:09:32 INFO - 'USER': 'cltbld', 19:09:32 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:32 INFO - '_': '/tools/buildbot/bin/python'} 19:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:32 INFO - Downloading/unpacking jsonschema==2.5.1 19:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:09:34 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 19:09:34 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 19:09:34 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 19:09:34 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 19:09:34 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 19:09:34 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 19:09:34 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:34 INFO - warning: no files found matching '*.txt' 19:09:34 INFO - no previously-included directories found matching 'build' 19:09:34 INFO - no previously-included directories found matching 'dist' 19:09:34 INFO - no previously-included directories found matching '.git*' 19:09:34 INFO - Installing collected packages: jsonschema, functools32 19:09:34 INFO - Running setup.py install for functools32 19:09:34 INFO - warning: no files found matching '*.txt' 19:09:34 INFO - no previously-included directories found matching 'build' 19:09:34 INFO - no previously-included directories found matching 'dist' 19:09:34 INFO - no previously-included directories found matching '.git*' 19:09:34 INFO - Successfully installed jsonschema functools32 19:09:34 INFO - Cleaning up... 19:09:34 INFO - Return code: 0 19:09:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:34 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:34 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 19:09:34 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 19:09:34 INFO - Using env: {'DISPLAY': ':0', 19:09:34 INFO - 'HOME': '/home/cltbld', 19:09:34 INFO - 'LANG': 'en_US.UTF-8', 19:09:34 INFO - 'LANGUAGE': 'en_US:en', 19:09:34 INFO - 'LOGNAME': 'cltbld', 19:09:34 INFO - 'MAIL': '/var/mail/cltbld', 19:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:34 INFO - 'MOZ_NO_REMOTE': '1', 19:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:34 INFO - 'NO_EM_RESTART': '1', 19:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:34 INFO - 'PWD': '/builds/slave/test', 19:09:34 INFO - 'SHELL': '/bin/bash', 19:09:34 INFO - 'SHLVL': '1', 19:09:34 INFO - 'TERM': 'linux', 19:09:34 INFO - 'TMOUT': '86400', 19:09:34 INFO - 'USER': 'cltbld', 19:09:34 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:34 INFO - '_': '/tools/buildbot/bin/python'} 19:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:09:35 INFO - Cleaning up... 19:09:35 INFO - Return code: 0 19:09:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:35 INFO - Using env: {'DISPLAY': ':0', 19:09:35 INFO - 'HOME': '/home/cltbld', 19:09:35 INFO - 'LANG': 'en_US.UTF-8', 19:09:35 INFO - 'LANGUAGE': 'en_US:en', 19:09:35 INFO - 'LOGNAME': 'cltbld', 19:09:35 INFO - 'MAIL': '/var/mail/cltbld', 19:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:35 INFO - 'MOZ_NO_REMOTE': '1', 19:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:35 INFO - 'NO_EM_RESTART': '1', 19:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:35 INFO - 'PWD': '/builds/slave/test', 19:09:35 INFO - 'SHELL': '/bin/bash', 19:09:35 INFO - 'SHLVL': '1', 19:09:35 INFO - 'TERM': 'linux', 19:09:35 INFO - 'TMOUT': '86400', 19:09:35 INFO - 'USER': 'cltbld', 19:09:35 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:35 INFO - '_': '/tools/buildbot/bin/python'} 19:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:35 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 INFO - Downloading docopt-0.6.1.tar.gz 19:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:37 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:37 INFO - Running setup.py install for blobuploader 19:09:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:38 INFO - Running setup.py install for requests 19:09:38 INFO - Running setup.py install for docopt 19:09:38 INFO - Successfully installed blobuploader requests docopt 19:09:38 INFO - Cleaning up... 19:09:38 INFO - Return code: 0 19:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:38 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:38 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 19:09:38 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 19:09:38 INFO - Using env: {'DISPLAY': ':0', 19:09:38 INFO - 'HOME': '/home/cltbld', 19:09:38 INFO - 'LANG': 'en_US.UTF-8', 19:09:38 INFO - 'LANGUAGE': 'en_US:en', 19:09:38 INFO - 'LOGNAME': 'cltbld', 19:09:38 INFO - 'MAIL': '/var/mail/cltbld', 19:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:38 INFO - 'MOZ_NO_REMOTE': '1', 19:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:38 INFO - 'NO_EM_RESTART': '1', 19:09:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:38 INFO - 'PWD': '/builds/slave/test', 19:09:38 INFO - 'SHELL': '/bin/bash', 19:09:38 INFO - 'SHLVL': '1', 19:09:38 INFO - 'TERM': 'linux', 19:09:38 INFO - 'TMOUT': '86400', 19:09:38 INFO - 'USER': 'cltbld', 19:09:38 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:38 INFO - '_': '/tools/buildbot/bin/python'} 19:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:38 INFO - Running setup.py (path:/tmp/pip-U0ud13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:38 INFO - Running setup.py (path:/tmp/pip-lyN801-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:38 INFO - Running setup.py (path:/tmp/pip-_MGF8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:38 INFO - Running setup.py (path:/tmp/pip-i2ec8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:39 INFO - Running setup.py (path:/tmp/pip-knXBxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:39 INFO - Running setup.py (path:/tmp/pip-fidmZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:39 INFO - Running setup.py (path:/tmp/pip-siUYse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:39 INFO - Running setup.py (path:/tmp/pip-VsicK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:39 INFO - Running setup.py (path:/tmp/pip-JpE9Y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:39 INFO - Running setup.py (path:/tmp/pip-epMs_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:39 INFO - Running setup.py (path:/tmp/pip-NjbDbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:39 INFO - Running setup.py (path:/tmp/pip-3kPQI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:39 INFO - Running setup.py (path:/tmp/pip-cPEB3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:39 INFO - Running setup.py (path:/tmp/pip-aQA6g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:39 INFO - Running setup.py (path:/tmp/pip-nr10MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:39 INFO - Running setup.py (path:/tmp/pip-kywdL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:39 INFO - Running setup.py (path:/tmp/pip-KGCSGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:39 INFO - Running setup.py install for manifestparser 19:09:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for mozcrash 19:09:40 INFO - Running setup.py install for mozdebug 19:09:40 INFO - Running setup.py install for mozdevice 19:09:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for mozfile 19:09:40 INFO - Running setup.py install for mozhttpd 19:09:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for mozinfo 19:09:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for mozInstall 19:09:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for mozleak 19:09:40 INFO - Running setup.py install for mozlog 19:09:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:40 INFO - Running setup.py install for moznetwork 19:09:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Running setup.py install for mozprocess 19:09:41 INFO - Running setup.py install for mozprofile 19:09:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Running setup.py install for mozrunner 19:09:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Running setup.py install for mozscreenshot 19:09:41 INFO - Running setup.py install for moztest 19:09:41 INFO - Running setup.py install for mozversion 19:09:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:41 INFO - Cleaning up... 19:09:41 INFO - Return code: 0 19:09:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:41 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:41 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 19:09:41 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 19:09:41 INFO - Using env: {'DISPLAY': ':0', 19:09:41 INFO - 'HOME': '/home/cltbld', 19:09:41 INFO - 'LANG': 'en_US.UTF-8', 19:09:41 INFO - 'LANGUAGE': 'en_US:en', 19:09:41 INFO - 'LOGNAME': 'cltbld', 19:09:41 INFO - 'MAIL': '/var/mail/cltbld', 19:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:41 INFO - 'MOZ_NO_REMOTE': '1', 19:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:41 INFO - 'NO_EM_RESTART': '1', 19:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:41 INFO - 'PWD': '/builds/slave/test', 19:09:41 INFO - 'SHELL': '/bin/bash', 19:09:41 INFO - 'SHLVL': '1', 19:09:41 INFO - 'TERM': 'linux', 19:09:41 INFO - 'TMOUT': '86400', 19:09:41 INFO - 'USER': 'cltbld', 19:09:41 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:41 INFO - '_': '/tools/buildbot/bin/python'} 19:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:41 INFO - Running setup.py (path:/tmp/pip-dK4eMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:42 INFO - Running setup.py (path:/tmp/pip-ueKVut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:42 INFO - Running setup.py (path:/tmp/pip-xI4ttL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:42 INFO - Running setup.py (path:/tmp/pip-XBt3IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:42 INFO - Running setup.py (path:/tmp/pip-6qIfNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:42 INFO - Running setup.py (path:/tmp/pip-SqiJwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:42 INFO - Running setup.py (path:/tmp/pip-fth8Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:42 INFO - Running setup.py (path:/tmp/pip-tNTasn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:42 INFO - Running setup.py (path:/tmp/pip-O6EfFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:42 INFO - Running setup.py (path:/tmp/pip-2y6aFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:42 INFO - Running setup.py (path:/tmp/pip-P2vk8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:42 INFO - Running setup.py (path:/tmp/pip-qDuZdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:42 INFO - Running setup.py (path:/tmp/pip-iqx5oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:42 INFO - Running setup.py (path:/tmp/pip-AYFpKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:42 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)) 19:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:42 INFO - Running setup.py (path:/tmp/pip-Wl3FUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:43 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)) 19:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:43 INFO - Running setup.py (path:/tmp/pip-fPQ3W1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:43 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)) 19:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:43 INFO - Running setup.py (path:/tmp/pip-ZUHBvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:43 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)) 19:09:43 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)) 19:09:43 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)) 19:09:43 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)) 19:09:43 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)) 19:09:43 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)) 19:09:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:43 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 19:09:43 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 19:09:43 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 19:09:43 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 19:09:43 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 19:09:43 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 19:09:45 INFO - Downloading blessings-1.6.tar.gz 19:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:45 INFO - Installing collected packages: blessings 19:09:45 INFO - Running setup.py install for blessings 19:09:45 INFO - Successfully installed blessings 19:09:45 INFO - Cleaning up... 19:09:45 INFO - Return code: 0 19:09:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:45 INFO - Reading from file tmpfile_stdout 19:09:45 INFO - Current package versions: 19:09:45 INFO - argparse == 1.2.1 19:09:45 INFO - blessings == 1.6 19:09:45 INFO - blobuploader == 1.2.4 19:09:45 INFO - docopt == 0.6.1 19:09:45 INFO - functools32 == 3.2.3-2 19:09:45 INFO - jsonschema == 2.5.1 19:09:45 INFO - manifestparser == 1.1 19:09:45 INFO - mozInstall == 1.12 19:09:45 INFO - mozcrash == 0.17 19:09:45 INFO - mozdebug == 0.1 19:09:45 INFO - mozdevice == 0.48 19:09:45 INFO - mozfile == 1.2 19:09:45 INFO - mozhttpd == 0.7 19:09:45 INFO - mozinfo == 0.9 19:09:45 INFO - mozleak == 0.1 19:09:45 INFO - mozlog == 3.3 19:09:45 INFO - moznetwork == 0.27 19:09:45 INFO - mozprocess == 0.23 19:09:45 INFO - mozprofile == 0.28 19:09:45 INFO - mozrunner == 6.12 19:09:45 INFO - mozscreenshot == 0.1 19:09:45 INFO - mozsystemmonitor == 0.3 19:09:45 INFO - moztest == 0.7 19:09:45 INFO - mozversion == 1.4 19:09:45 INFO - psutil == 3.1.1 19:09:45 INFO - requests == 1.2.3 19:09:45 INFO - wsgiref == 0.1.2 19:09:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:45 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 19:09:45 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 19:09:45 INFO - Using env: {'DISPLAY': ':0', 19:09:45 INFO - 'HOME': '/home/cltbld', 19:09:45 INFO - 'LANG': 'en_US.UTF-8', 19:09:45 INFO - 'LANGUAGE': 'en_US:en', 19:09:45 INFO - 'LOGNAME': 'cltbld', 19:09:45 INFO - 'MAIL': '/var/mail/cltbld', 19:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:45 INFO - 'MOZ_NO_REMOTE': '1', 19:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:45 INFO - 'NO_EM_RESTART': '1', 19:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:45 INFO - 'PWD': '/builds/slave/test', 19:09:45 INFO - 'SHELL': '/bin/bash', 19:09:45 INFO - 'SHLVL': '1', 19:09:45 INFO - 'TERM': 'linux', 19:09:45 INFO - 'TMOUT': '86400', 19:09:45 INFO - 'USER': 'cltbld', 19:09:45 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:45 INFO - '_': '/tools/buildbot/bin/python'} 19:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 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)) 19:09:45 INFO - Cleaning up... 19:09:45 INFO - Return code: 0 19:09:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 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 0xca4030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed3400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, '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': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', '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 19:09:45 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 19:09:45 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 19:09:45 INFO - Using env: {'DISPLAY': ':0', 19:09:45 INFO - 'HOME': '/home/cltbld', 19:09:45 INFO - 'LANG': 'en_US.UTF-8', 19:09:45 INFO - 'LANGUAGE': 'en_US:en', 19:09:45 INFO - 'LOGNAME': 'cltbld', 19:09:45 INFO - 'MAIL': '/var/mail/cltbld', 19:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:45 INFO - 'MOZ_NO_REMOTE': '1', 19:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:45 INFO - 'NO_EM_RESTART': '1', 19:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:45 INFO - 'PWD': '/builds/slave/test', 19:09:45 INFO - 'SHELL': '/bin/bash', 19:09:45 INFO - 'SHLVL': '1', 19:09:45 INFO - 'TERM': 'linux', 19:09:45 INFO - 'TMOUT': '86400', 19:09:45 INFO - 'USER': 'cltbld', 19:09:45 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:45 INFO - '_': '/tools/buildbot/bin/python'} 19:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:09:45 INFO - Cleaning up... 19:09:45 INFO - Return code: 0 19:09:45 INFO - Running post-action listener: _resource_record_post_action 19:09:45 INFO - Running post-action listener: _start_resource_monitoring 19:09:45 INFO - Starting resource monitoring. 19:09:45 INFO - [mozharness: 2016-08-13 02:09:45.984287Z] Finished create-virtualenv step (success) 19:09:45 INFO - [mozharness: 2016-08-13 02:09:45.984758Z] Running install step. 19:09:45 INFO - Running pre-action listener: _resource_record_pre_action 19:09:45 INFO - Running main action method: install 19:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:46 INFO - Reading from file tmpfile_stdout 19:09:46 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:46 INFO - Reading from file tmpfile_stdout 19:09:46 INFO - Output received: 19:09:46 INFO - Usage: mozinstall [options] installer 19:09:46 INFO - Options: 19:09:46 INFO - -h, --help show this help message and exit 19:09:46 INFO - -d DEST, --destination=DEST 19:09:46 INFO - Directory to install application into. [default: 19:09:46 INFO - "/builds/slave/test"] 19:09:46 INFO - --app=APP Application being installed. [default: firefox] 19:09:46 INFO - mkdir: /builds/slave/test/build/application 19:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:10:02 INFO - Reading from file tmpfile_stdout 19:10:02 INFO - Output received: 19:10:02 INFO - /builds/slave/test/build/application/firefox/firefox 19:10:02 INFO - Running post-action listener: _resource_record_post_action 19:10:02 INFO - [mozharness: 2016-08-13 02:10:02.839962Z] Finished install step (success) 19:10:02 INFO - [mozharness: 2016-08-13 02:10:02.840239Z] Running run-tests step. 19:10:02 INFO - Running pre-action listener: _resource_record_pre_action 19:10:02 INFO - Running main action method: run_tests 19:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:10:02 INFO - Python 2.7.3 19:10:02 INFO - Return code: 0 19:10:02 INFO - grabbing minidump binary from tooltool 19:10:02 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')]} 19:10:02 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 0xf62570>, '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 0xf63470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf63900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:10:02 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 19:10:02 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 19:10:02 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 19:10:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:02 INFO - Return code: 0 19:10:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:10:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:10:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:10:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:10:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:10:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:10:02 INFO - Using env: {'DISPLAY': ':0', 19:10:02 INFO - 'HOME': '/home/cltbld', 19:10:02 INFO - 'LANG': 'en_US.UTF-8', 19:10:02 INFO - 'LANGUAGE': 'en_US:en', 19:10:02 INFO - 'LOGNAME': 'cltbld', 19:10:02 INFO - 'MAIL': '/var/mail/cltbld', 19:10:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:10:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:02 INFO - 'MOZ_NO_REMOTE': '1', 19:10:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:02 INFO - 'NO_EM_RESTART': '1', 19:10:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:10:02 INFO - 'PWD': '/builds/slave/test', 19:10:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:10:02 INFO - 'SHELL': '/bin/bash', 19:10:02 INFO - 'SHLVL': '1', 19:10:02 INFO - 'TERM': 'linux', 19:10:02 INFO - 'TMOUT': '86400', 19:10:02 INFO - 'USER': 'cltbld', 19:10:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919', 19:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:02 INFO - '_': '/tools/buildbot/bin/python'} 19:10:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:10:03 INFO - mozversion application_buildid: 20160812154615 19:10:03 INFO - mozversion application_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 19:10:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:10:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:03 INFO - mozversion application_name: Firefox 19:10:03 INFO - mozversion application_remotingname: firefox-dev 19:10:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:03 INFO - mozversion application_vendor: Mozilla 19:10:03 INFO - mozversion application_version: 50.0a2 19:10:03 INFO - mozversion platform_buildid: 20160812154615 19:10:03 INFO - mozversion platform_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 19:10:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:10:03 INFO - mozversion platform_version: 50.0a2 19:10:03 INFO - using testdate: 1471054203 19:10:03 INFO - actual date: 1471054203 19:10:03 INFO - starting webserver on 'localhost:54592' 19:10:03 INFO - SUITE-START | Running 2 tests 19:10:03 INFO - TEST-START | dromaeo_css 19:10:03 INFO - operating with platform_type : linux_ 19:10:03 INFO - Initialising browser for dromaeo_css test... 19:10:03 INFO - TEST-INFO | started process 31376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp122JES/profile http://localhost:54592/getInfo.html) 19:10:07 INFO - PROCESS | 31376 | __metrics Screen width/height:1600/1200 19:10:07 INFO - PROCESS | 31376 | colorDepth:24 19:10:07 INFO - PROCESS | 31376 | Browser inner width/height: 1024/697 19:10:07 INFO - PROCESS | 31376 | __metrics 19:10:08 ERROR - PROCESS | 31376 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 19:10:08 INFO - TEST-INFO | 31376: exit 0 19:10:08 INFO - Browser initialized. 19:10:08 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:09 INFO - TEST-INFO | started process 31505 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp122JES/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:20 INFO - PROCESS | 31505 | RSS: Main: 152649728 19:10:20 INFO - PROCESS | 31505 | 19:11:10 INFO - PROCESS | 31505 | 1471054270319 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] 19:13:18 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54592/tests/dromaeo/cssquery-ext.html) 19:13:19 INFO - PROCESS | 31505 | RSS: Main: 157519872 19:13:19 INFO - PROCESS | 31505 | 19:16:21 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-ext.html (next: http://localhost:54592/tests/dromaeo/cssquery-jquery.html) 19:16:22 INFO - PROCESS | 31505 | RSS: Main: 153817088 19:16:22 INFO - PROCESS | 31505 | 19:19:24 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54592/tests/dromaeo/cssquery-mootools.html) 19:19:24 INFO - PROCESS | 31505 | RSS: Main: 154062848 19:19:24 INFO - PROCESS | 31505 | 19:22:26 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54592/tests/dromaeo/cssquery-prototype.html) 19:22:26 INFO - PROCESS | 31505 | RSS: Main: 152342528 19:22:26 INFO - PROCESS | 31505 | 19:25:29 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54592/tests/dromaeo/cssquery-yui.html) 19:25:30 INFO - PROCESS | 31505 | RSS: Main: 154505216 19:25:30 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | Cycle 1(1): loaded http://localhost:54592/tests/dromaeo/cssquery-yui.html (next: http://localhost:54592/tests/dromaeo/cssquery-dojo.html) 19:28:32 INFO - PROCESS | 31505 | RSS: Main: 154521600 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | __start_tp_report 19:28:32 INFO - PROCESS | 31505 | _x_x_mozilla_page_load 19:28:32 INFO - PROCESS | 31505 | _x_x_mozilla_page_load_details 19:28:32 INFO - PROCESS | 31505 | |i|pagename|runs| 19:28:32 INFO - PROCESS | 31505 | |0;dojo.html;3320;3333.133385319816;3338.2155193529516;3349.1459677782163;3364.6635336466356;5826.533877289818;5848.4736373726355;5851.9999999999945;5854.88290234195;5917.49701275391;5740.512056475204;5811.767529298823;5839.854003649911;5862.824115276538;5870.383609720971;5243.475652434764;5269.65747226431;5309.150535914235;5325.733713314338;5343.919841202385;5225.895482090375;5229.947700522984;5256.185291279858;5263.26314315996;5297.496737809902;5276.076686579833;5288.788448462057;5289.471052894699;5351.892962140755;5393.083175860113;5315.495027972336;5318;5338.0925242708445;5341.545968592674;5357.544608708265;5075.5178258065425;5133.974330128325;5152.896942061137;5245.213218017289;5286.259923610698;5665.206871038058;5721.256236689203;5722.885542289131;5729.799457018984;5768.798092066758;6128;6131.926912790288;6135.171751813493;6141.078838174265;6143.600665956743;6102.115193296947;6134.283518479682;6209.16176316196;6212.409821067036;6231.252249730016;5769.88460230793;5825.271841019872;5840;5879.441453061952;5893.79371721992;6365.395287447685;6383.4574061204385;6388.041793730885;6403.039544068427;6405.711742971577;5904.763809447574;6012.849678757979;6077.574569780161;6101.633901965896;6138.600990935575;6111.419415155553;6207.96896015517;6212.4408803207925;6240.65676387794;6242.65665388408;6135.631862088199;6184;6211;6215.658138802319;6216.564840461125;6028.0355143176885;6037.275526936795;6190.1952746142715;6198.597091189148;6207.844803879939;4665.323528088476;4698.083873644607;4706.741129237925;4733.905321893543;4737.336772851803;4167.1040726244;4172.08214192877;4176.519700234512;4213.241616508996;4218;5763.625364351304;5806.767729290781;5845.532357411397;5852.94147058524;5882.235309409835;6106.694665266719;6157.630542167484;6173.783917562864;6194.783182588588;6199.411055949678;5877.265341832271;5877.265341832271;5898.557608179403;5906.793262235801;5938.2280303560265;5261.684298942076;5285.735713214401;5307.734613269321;5309.41596424393;5316.255724198615;6348.936510634927;6458.935410645928;6460.903086453707;6467.870642587122;6484.513661475408;6166.414190651881;6194.752209911553;6205.2553693557065;6230.470410015108;6250.124982502544;6166.845828854314;6285.528585356025;6314.368563143833;6452.709628066842;6465.321141280097;6116.214037636738;6127.433875357673;6165;6171.290301615192;6178.969105154445;6224.315325314121;6246.46905013079;6257.186565746424;6262.937370626236;6268.310485846462;5942.851428714317;6017.789377371771;6020.307664618625;6113.51091912637;6119.112728654409;5989.880202396015;6004.219451471281;6030.969845150922;6082.239720034996;6103;5958.731857066356;5981.282246130492;5983.072623743326;6023.427774361428;6023.969880150571;6007;6089.1779609753885;6113.749968914014;6119.571629985859;6151.753929842756;6022.45797878193;6043.697815109139;6063.848403789941;6071.51427885777;6075.817725468243 19:28:32 INFO - PROCESS | 31505 | |1;ext.html;587.3363099697341;627.780276903084;653.4837478392069;654.744649586661;684.1584850633724;39946.60053399465;44494.55505444945;44584.10831783363;44677.32984005242;44700;44663.77668111663;45211.32183017257;45268.3209751854;45305;45322.32016519747;9083.137101975319;9286.767830804216;9303.162715355616;9307.069293070706;9311.813763724722;9170.44977301364;9264.212541933945;9296.535173241346;9328.300377471684;9350.766230844249;9274.165325120737;9298.86051709225;9315.115064068936;9334.953325233364;9385.906140938605;9323.440593564408;9343.252539796835;9351.158395744347;9367.484788336638;9371.250299975985;9259.166674999282;9272.443653380751;9273.07269273074;9283.58223879927;9298.302627302912;42366;42509.36235956463;42717.14565708699;42997.92505187364;43001.1399772006;3109.4713898637287;3765.7928813915223;3822.9426558601645;3871.9225615487812;4388.561143885618;31876.362472750414;32588;32832.83583582091;32869;33266.50100248498;4518.502964673883;4563.908721825578;4630.421197350332;4674.415698037746;4727.101850648366;297685;297778;298143.99999999785;298186;298715.99999999785;38213.23573528569;38374.424383634265;38518.22963540714;38588.61411385906;38674.41988370177;38447.80776096102;39415.211695765924;39501;40725.59274407218;40873.386899196536;18173;18673.719894201597;18722.344717934808;18752.81247187538;18802.247910083715;14882.702345953237;14896.925515372353;14931.552053438414;14984.325705343068;15019.09885406879;20512.487187820425;20666.17335306601;20779.376618701463;20817.791822081585;20885.37343879686;7372.778816635509;7652.196519365498;7739.226077392215;7743.4966727162555;7751.806204844924;1025.6974192613197;1130.5082289204058;1192.713748700323;1199.7600479904054;1209.776191404593;31711;32121.678783211868;32555.34889302201;32656.83671581627;32911.835440823124;1088.6733979806186;1108.6784832398514;1120.7590368070903;1150.4880328254014;1166.1661911733152;17380.3047878086;17460.476185714448;17626.118694065502;17668.91165544164;17679.73480397795;429800;432086;434788;435795;438045;57784;58053.419465805644;58258.70870645619;58368.7081564598;58535.707321463124;1446.2696338349067;1453.6656568989192;1476.438953197778;1480.3338497676089;1480.9851901480847;30448.695513044586;31232.531512026882;31526;31757.948984539296;32146.67853321437;53032.73483632557;53626.73186634042;53635;53673.19490207572;53674.73162634162;377060;377415;377973;378041;378966;30108.69891301059;30137.698623014367;30312.393752125274;30573.54139687862;30589;48825.75587122042;48942.75528622334;49181.75409122931;49342.25986610132;50889.745551272004;41395;41505.1698966025;41527;42094.15811683688;43715.12569748651;42249.366259505514;42436.575634243265;42498.575014249465;43516;43560.564394356916;38928;42339.364909527;44207;44208.115837682424;46555.767221163675;36123.63876361308;38360;38458;38612.80693596626;38725.6127438722;26767.464650707265;27048.72951270541;27250.182494525197;27318.863405682845;27456.725432745417 19:28:32 INFO - PROCESS | 31505 | |2;jquery.html;409.6681687832856;416.4648426771597;416.6229562246165;420.24775651583667;425.4065578517968;19344.709829352552;20764.896175519138;20898.477538061536;21770.89114554429;21845.235416760417;20724.27465038724;20799.376018719424;20969.895150524226;21205.787942120573;21439.356819295444;5785.537157027438;5993.370696076914;6027.156198132265;6036.5170786336985;6044.455998960093;5969.940300596982;5970.462658360747;5974.163617093611;6002.459778619924;6023.879522409571;5777;5916.408359164071;5933.495652869511;5952;5981.671008094575;5881.823545293648;5907.616004959686;5928.822135335969;5933.851653708649;5957.910631340534;5343.839684809462;5359.517643412073;5369.5972802040005;5372.274742909735;5379.085555455582;22451.438714032116;23197;24040.519189616283;24583.13959011444;24645.753542464525;2394.31761947844;2438.756124387565;2439.6340548917806;2465.963010554843;2485.005997600956;11031.393273369962;11093.27893686916;11137.610183643534;11225.60710375133;11436.828447573293;2046.9590608187902;2073.6889466580133;2076.8753874767563;2086.0925497408525;2189.2157891318684;86306.5684671579;86931.56534217288;87542;87795.56102219512;88393;9163.725088247364;9327.813443731087;9942.80114397708;11524.711882203012;11863.58477453285;16588.336466541205;16707.749383759252;16709;16716.24776885043;16793.244304006348;8762.342824288204;8983.910160898307;8986.370954033286;9123.042080581443;9255.352125351295;8781.956090219635;8794.780130496662;8929.508877011696;8932.508712020772;9052.366334356531;4592.425946756655;4698.600618947427;5040.722760248222;5053.823116190915;5107.080725469451;2053.188990348804;2136.6474531702406;2145.978540214609;2162.426956856434;2164.188429338998;1002.3384566186282;1004.8794144702682;1086.266769930294;1088.3034857691084;1095.2606990281529;20750.377488675364;20916.686249706265;20978.580428391346;21018.47453813667;21244.468888277606;1051.542578978147;1059.4755595980039;1068.9358638481715;1073.9248252622397;1114.1309778372881;11634;11663.941680291544;11758.882411175779;11902.940485297519;12089.879101209051;469377;469616;470214;478660;495356;93124;93133.53433232791;93360.47213533575;98428.50785746025;100935.49532252438;1054.5465449856636;1091.1925175370168;1104.5305745058317;1106.618216715232;1111.2776695148057;18246.270149194414;18255.726164107284;18510.537236568656;18978.810211897704;19299.324523641604;40019;40607.18785624212;43843.78078109589;44353.55646443494;44548;59709.40290597038;63256;64018;64464.677676613195;64671;8693.869591956252;8889.73330800077;8928.15182557656;8972;9150.450972941644;10545.841812372973;13474.79787803164;13487.05590608648;13635.181889086687;13724.245166515937;12053.337066461077;12123.818142728043;12633.052521060774;12647.304398258184;12809;12048.337341446304;12201.755964880831;12229.14395992272;12355.444005019615;12608;12639.87360126387;12644.178128421623;12694.428750706425;12849.614511564669;13107.082504224994;8603.09667484905;8906.95546522289;9057.411268267542;12550.30973296441;13139;12256.264624122581;12518;13039.674008149872;13067.738645227233;13209.933950330187 19:28:32 INFO - PROCESS | 31505 | |3;mootools.html;1561.3988614383463;1591.2839222349937;1781.447751197129;1870.8783929044619;1874.6625607390665;8742.562871856413;9311.627534898604;9491.288153388488;10118.291719579624;10130.746731331712;9523.714288571337;9820.214382849374;9826.459544725038;9830.016998300167;9830.852537211947;2501.974980250197;2558.373198566354;2560.8591527465983;2565.076572433922;2568.3322336192537;2465.506898620283;2529.3676580854785;2537.8857951392138;2538.9111381101666;2545.147375629157;2519.6472493850874;2526.7725904668578;2548.872556372174;2555.948881022388;2557.552428325045;2468.2841975826987;2485.2668462803517;2488.688913885764;2490.7011158660985;2491.6262560615874;2398.3404563744934;2403.471236328005;2410.0480310277517;2411.9276421707377;2446.4862378900452;9888.95055524725;10015.849762253572;10030.297879148462;10044.547995340226;10049.346792458467;5653.886922261532;5803.303603567599;5810.041343178371;5825.15535247391;5844.795432159896;3590.1922067534856;3694.2426802505693;3701.1857391373856;3749.043993781568;3773.1699026214187;1249.8125281207892;1252.6492582077033;1314.4479318686174;1317.5915466205506;1336.6324260828253;13468.663283417993;13551.186928784304;13655.248961307125;13919.930400348036;13923.164610123426;4917.533747535979;4940.135476291634;5171.663841850268;5172.508611681884;5188.11801993662;11539.653810385702;11596.072314214842;11600.76798464026;11613.070954323633;11628;5868.765249390062;5891.675957822275;5896.439838215363;5898.498627616701;5911.320198177179;4701.247800351927;5238.842834714965;5356.4107948125675;5365.356157261075;5397.082495975694;4268.125034367947;4377.606015458624;4410.470743510735;4419.734815911055;4441.578050085237;1307.9738405232033;1323.3912400295778;1329.0962145740796;1353.2151352215692;1379.5171689908452;1008.3949630221808;1085.5169449594855;1106.8118419868642;1110.5946329589642;1121.6466812953893;6661.26726060133;6678.198616165994;6776;6802.625855577891;6845.349691779273;496.8956519130951;504.1052132464831;516.8862850172956;517.6014468858957;521.1661341853005;4685.320628508847;4720.504347043579;4757.191277482836;4804.807807687724;4814.566688998007;11688.298702077902;12037.157398981988;12113.939430302968;12116.09129315305;12136.150469467053;11522.539098436137;11900.76198476043;11911.52353905834;11984.34086125254;12021.819672704916;9007.279417646572;9136.177744003071;9173.633054677872;9186.678466253648;9247.306452016126;6673.099131617169;6698.196216454056;6745.898811517733;6752.459803215829;6892.620905850025;10675.306105103144;10890.128789696964;10912.945435272773;10983.725406864893;11251.099912006855;11208.719782005515;11220.943895280472;11234.157438192005;11283.492242849255;11338.14963877696;8792.912070879209;8835.07231740658;8978;8991.325650576364;9015.81968360616;9451.480168590793;9455.338126331091;9532.666356677482;9543.33196676226;9639.180669642878;8272.420930535045;8403.83192336162;8490.702825401082;8491.74524764258;8672.956635216784;8180.631871565674;8271.131531189138;8279.668813247403;8309.127541608044;8449.915500844912;7793.337566306701;7889.171636978034;7890.368770498231;7897.368210543028;7903.723369682033;5866.823995280149;5881.617694849821;5981.641101533922;5984.8803023940145;6055.636661800306;5961.344252132349;6025.367336429787;6026.096085587197;6112.144299798121;6156.78451254204 19:28:32 INFO - PROCESS | 31505 | |4;prototype.html;330.3755901346454;354.1094148217234;360.10513873025525;365.24394503378016;369.6100613852386;5691.260136182301;5783.2481777368985;5869.471747542721;5921.911171332434;5941.673207973565;5755.740991655375;5756.856078598037;5780.624259423134;5786.594938354307;5823;4492.752898590568;4512.548745125494;4521.367008618796;4530.165283060366;4531.663159367978;4452.8441504547345;4545.8408955686555;4548.9090218195615;4597.149527337453;4603.677742558022;4500.347449619803;4509.436320459942;4522.4346956630425;4535.546445355454;4538.841140560081;4525.185466616008;4535.22901106811;4548.636109111296;4559.110973360196;4564.657650676179;3887.5529314128953;4026.879193624196;4031.3348297530874;4035.778032208227;4073.1853629274265;5626.381098079208;5637.887242255173;5659.122835960433;5675.5459563234845;5689.829305120853;5256.632035757499;5268.130758424855;5281.86795330116;5305.4614161893005;5306.815910453697;5787.681677507735;6027.578069535134;6035.275766907999;6048.153258543807;6200.81397558074;4351.477822661269;4373.431453911003;4386.6929314947965;4389.31965545341;4407.955920440819;6147.139400483891;6363.872722545523;6372.522060845455;6409.9679501602195;6426.389492998158;4419.513853476115;4433.334999749999;4436.112777444524;4446.820523325838;4449.1991441540185;4532.4334458192725;4548.248924164221;4551.7724113794175;4553.476350219701;4558.5897269245925;5119.692818430906;5184.351956005499;5198.298229739013;5208.348956380452;5213.296205012351;5791.102379131242;6035.124906888476;6067.362926892701;6080.543959203077;6099.817005489843;1513.6291608555973;1551.5965848879148;1564.3429759501037;1565.2330358124664;1568.7019466301367;964.2189826240767;969.3021024862088;972.9443553744267;991.9156871665845;996.7807082441851;5084.3644544431945;5161.071007218661;5161.458046905021;5185.325907631984;5192.273081768555;5435.5324062502905;5452.727363631802;5476.972615136979;5499.1476321170285;5508.779648814083;5028.094942910238;5191.844244672666;5209;5257.316548848626;5259.715294317321;2954.335274563249;2956.1427186116216;2956.49739544278;2971.5439434676855;2978.627671541057;5522.889542209134;5582.692951887685;5583.748731307101;5598.300212473441;5602.915956260659;5184.429712731597;5512.80705175317;5529.336479622471;5547.77808887648;5553.8333849984565;5522.22688823565;5574.972125139429;5583.69289689071;5602.915956260659;5630.5495560355075;5125.743712814419;5444.8094316698725;5454.863628409322;5473.945260547343;5527.452313352268;5378.467136865975;5384.784608615611;5388.030154572215;5429.945700542944;5467;5521.834344969658;5651.6043876928225;5660.433956604306;5688.919105369966;5733.770649174153;4084.1627466368727;4118.608732170439;4140.503139623264;4461.286194208911;4497.640188784955;6178.41305076035;6274.278457977391;6279.466245369194;6290.46531044848;6336.429721325103;6174.734179493283;6183.227096612924;6186.257649082139;6187.938120618916;6205.751769929152;6031.728572214349;6123.663198524124;6148.569600127948;6174;6181.196444462371;6098.75604975796;6121.081837724377;6130.693465326626;6152.446279834658;6174.116894347268;6228.28374736893;6230.283517395557;6279.403456671609;6280.5289603279025;6301.4328710416285;5784.727359980798;6016.729247183975;6070;6085.208922840003;6144.201253836973 19:28:32 INFO - PROCESS | 31505 | |5;yui.html;794.9085855126658;799.956002419867;839.7354833227537;883.3154305413304;891.8216356728656;14455.349509272082;15541.456049038286;16385.344586216535;16407.097609631466;16413.589660258498;17566.912165439186;18211;18225.54436139097;18339.26642934281;18399.172037258322;843.0852525010371;859.5014891363026;860.4364141487335;867.5055218525443;870.2950610005884;856.7601071699934;870.2994089757774;874.6588830356167;877.079066979672;885.5173930208019;930.1721467893547;934.3039435620473;935.6304259817368;936.6955739384694;937.0816599732267;919.7056941778634;919.9908000919972;921.0927236671906;924.4314746430964;929.0384452092086;676.8409423785406;679.0628932073756;681.7613835157655;682.9282925292872;687.8555503344304;13416.195028298333;13498.865011349957;13562.59312220635;13605.251711155883;13645.317734113254;15664.295106720225;15846.366145354172;15929.681406371808;15984.76022859658;16010.439634612732;13071.346432678329;13357.265350405723;13519.526816561372;13534.458621655122;13642.522511712043;4182.749035057906;4204.663626909871;4210.389493523453;4244.3209086546;4267.487901451846;89533.10466895312;91136;91353.54323228408;91358.54320728421;93207.53396233043;11765.705857353463;11983.28100313984;12019.33893635841;12400.937995309965;12510.124291299522;22319.44201394978;23728;23741.52516949651;23744.525109497714;23750.88124559366;9548.952255238679;9581.904180958241;9593;9603;9959.452230127268;5205.2192171173865;5250.264962905197;5265.552428043659;5309.177077552986;5362.034833729888;3880.262750077476;3997.2804895118575;4007.3788562772816;4018.778967156776;4034.2738307105;666.044226534924;670.4368330602317;672.8856094463953;695.2838576266454;698.2005603583868;759.7264984605538;765.8148219977737;777.6578305545542;795.586295126531;796.279367172698;10046.095851373411;10047.095761381364;10083.344582602107;10185.287029907984;10222.182225421948;672.9831754206184;730.3755289227634;808.2765924497659;816.9223923727236;831.9417640765209;4462.6876118672035;4645.3032045193495;4707.952920470751;4738.076075165379;4780.067886762117;89429.55285223533;109253.45373273082;110993;113338.43330783458;114850;96608.51695741617;97812;100226.4988675052;100449.49775251077;103851;84664.57667711622;87946.56026719825;91917;95504.52247738717;97131.51434242925;9313;9440.763980900532;9515.714528564155;9548.761280968032;9621.615135394786;38843;48701.75649121732;48809.2678609814;50085.499145008085;52379.73810130925;75843.62078189795;78608.60695696485;78725;82608;83407.5829620848;14146.504872329419;14455.710885782015;14515.419383224553;14573.271336433349;15246.31391587359;15595.688086237984;15603.843961560693;15604.68790624204;16924.153792310088;16954.660906782043;15016.474423395566;15601.609959751097;15841.920790395974;16236.918815405847;16860.1569921501;13977.720445591234;14905.776413353591;15079.095254284777;15571.84428155704;16121.596960076093;14801;15132.546023619308;15200.46798362052;15448.227588620748;15582.532524024298;12353.444095015926;12427;12713.17364371313;12851.678708032374;13327.416242414634;11700.531978721096;11876.465559049691;13401.32993350348;13533.932330338286;13598.93200533991 19:28:32 INFO - PROCESS | 31505 | __end_tp_report 19:28:32 INFO - PROCESS | 31505 | __start_cc_report 19:28:32 INFO - PROCESS | 31505 | _x_x_mozilla_cycle_collect,325 19:28:32 INFO - PROCESS | 31505 | __end_cc_report 19:28:32 INFO - PROCESS | 31505 | __startTimestamp1471055312637__endTimestamp 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | ------- Summary: start ------- 19:28:32 INFO - PROCESS | 31505 | Number of tests: 6 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#0] dojo.html Cycles:165 Average:5756.60 Median:6007.00 stddev:658.64 (11.0%) stddev-sans-first:632.32 19:28:32 INFO - PROCESS | 31505 | Values: 3320.0 3333.1 3338.2 3349.1 3364.7 5826.5 5848.5 5852.0 5854.9 5917.5 5740.5 5811.8 5839.9 5862.8 5870.4 5243.5 5269.7 5309.2 5325.7 5343.9 5225.9 5229.9 5256.2 5263.3 5297.5 5276.1 5288.8 5289.5 5351.9 5393.1 5315.5 5318.0 5338.1 5341.5 5357.5 5075.5 5134.0 5152.9 5245.2 5286.3 5665.2 5721.3 5722.9 5729.8 5768.8 6128.0 6131.9 6135.2 6141.1 6143.6 6102.1 6134.3 6209.2 6212.4 6231.3 5769.9 5825.3 5840.0 5879.4 5893.8 6365.4 6383.5 6388.0 6403.0 6405.7 5904.8 6012.8 6077.6 6101.6 6138.6 6111.4 6208.0 6212.4 6240.7 6242.7 6135.6 6184.0 6211.0 6215.7 6216.6 6028.0 6037.3 6190.2 6198.6 6207.8 4665.3 4698.1 4706.7 4733.9 4737.3 4167.1 4172.1 4176.5 4213.2 4218.0 5763.6 5806.8 5845.5 5852.9 5882.2 6106.7 6157.6 6173.8 6194.8 6199.4 5877.3 5877.3 5898.6 5906.8 5938.2 5261.7 5285.7 5307.7 5309.4 5316.3 6348.9 6458.9 6460.9 6467.9 6484.5 6166.4 6194.8 6205.3 6230.5 6250.1 6166.8 6285.5 6314.4 6452.7 6465.3 6116.2 6127.4 6165.0 6171.3 6179.0 6224.3 6246.5 6257.2 6262.9 6268.3 5942.9 6017.8 6020.3 6113.5 6119.1 5989.9 6004.2 6031.0 6082.2 6103.0 5958.7 5981.3 5983.1 6023.4 6024.0 6007.0 6089.2 6113.7 6119.6 6151.8 6022.5 6043.7 6063.8 6071.5 6075.8 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#1] ext.html Cycles:180 Average:53891.78 Median:41134.19 stddev:98400.39 (239.2%) stddev-sans-first:98595.03 19:28:32 INFO - PROCESS | 31505 | Values: 587.3 627.8 653.5 654.7 684.2 39946.6 44494.6 44584.1 44677.3 44700.0 44663.8 45211.3 45268.3 45305.0 45322.3 9083.1 9286.8 9303.2 9307.1 9311.8 9170.4 9264.2 9296.5 9328.3 9350.8 9274.2 9298.9 9315.1 9335.0 9385.9 9323.4 9343.3 9351.2 9367.5 9371.3 9259.2 9272.4 9273.1 9283.6 9298.3 42366.0 42509.4 42717.1 42997.9 43001.1 3109.5 3765.8 3822.9 3871.9 4388.6 31876.4 32588.0 32832.8 32869.0 33266.5 4518.5 4563.9 4630.4 4674.4 4727.1 297685.0 297778.0 298144.0 298186.0 298716.0 38213.2 38374.4 38518.2 38588.6 38674.4 38447.8 39415.2 39501.0 40725.6 40873.4 18173.0 18673.7 18722.3 18752.8 18802.2 14882.7 14896.9 14931.6 14984.3 15019.1 20512.5 20666.2 20779.4 20817.8 20885.4 7372.8 7652.2 7739.2 7743.5 7751.8 1025.7 1130.5 1192.7 1199.8 1209.8 31711.0 32121.7 32555.3 32656.8 32911.8 1088.7 1108.7 1120.8 1150.5 1166.2 17380.3 17460.5 17626.1 17668.9 17679.7 429800.0 432086.0 434788.0 435795.0 438045.0 57784.0 58053.4 58258.7 58368.7 58535.7 1446.3 1453.7 1476.4 1480.3 1481.0 30448.7 31232.5 31526.0 31757.9 32146.7 53032.7 53626.7 53635.0 53673.2 53674.7 377060.0 377415.0 377973.0 378041.0 378966.0 30108.7 30137.7 30312.4 30573.5 30589.0 48825.8 48942.8 49181.8 49342.3 50889.7 41395.0 41505.2 41527.0 42094.2 43715.1 42249.4 42436.6 42498.6 43516.0 43560.6 38928.0 42339.4 44207.0 44208.1 46555.8 36123.6 38360.0 38458.0 38612.8 38725.6 26767.5 27048.7 27250.2 27318.9 27456.7 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#2] jquery.html Cycles:180 Average:29615.71 Median:22824.22 stddev:78857.81 (345.5%) stddev-sans-first:79048.54 19:28:32 INFO - PROCESS | 31505 | Values: 409.7 416.5 416.6 420.2 425.4 19344.7 20764.9 20898.5 21770.9 21845.2 20724.3 20799.4 20969.9 21205.8 21439.4 5785.5 5993.4 6027.2 6036.5 6044.5 5969.9 5970.5 5974.2 6002.5 6023.9 5777.0 5916.4 5933.5 5952.0 5981.7 5881.8 5907.6 5928.8 5933.9 5957.9 5343.8 5359.5 5369.6 5372.3 5379.1 22451.4 23197.0 24040.5 24583.1 24645.8 2394.3 2438.8 2439.6 2466.0 2485.0 11031.4 11093.3 11137.6 11225.6 11436.8 2047.0 2073.7 2076.9 2086.1 2189.2 86306.6 86931.6 87542.0 87795.6 88393.0 9163.7 9327.8 9942.8 11524.7 11863.6 16588.3 16707.7 16709.0 16716.2 16793.2 8762.3 8983.9 8986.4 9123.0 9255.4 8782.0 8794.8 8929.5 8932.5 9052.4 4592.4 4698.6 5040.7 5053.8 5107.1 2053.2 2136.6 2146.0 2162.4 2164.2 1002.3 1004.9 1086.3 1088.3 1095.3 20750.4 20916.7 20978.6 21018.5 21244.5 1051.5 1059.5 1068.9 1073.9 1114.1 11634.0 11663.9 11758.9 11902.9 12089.9 469377.0 469616.0 470214.0 478660.0 495356.0 93124.0 93133.5 93360.5 98428.5 100935.5 1054.5 1091.2 1104.5 1106.6 1111.3 18246.3 18255.7 18510.5 18978.8 19299.3 40019.0 40607.2 43843.8 44353.6 44548.0 59709.4 63256.0 64018.0 64464.7 64671.0 8693.9 8889.7 8928.2 8972.0 9150.5 10545.8 13474.8 13487.1 13635.2 13724.2 12053.3 12123.8 12633.1 12647.3 12809.0 12048.3 12201.8 12229.1 12355.4 12608.0 12639.9 12644.2 12694.4 12849.6 13107.1 8603.1 8907.0 9057.4 12550.3 13139.0 12256.3 12518.0 13039.7 13067.7 13209.9 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#3] mootools.html Cycles:180 Average:6437.84 Median:4563.45 stddev:3681.99 (80.7%) stddev-sans-first:3674.08 19:28:32 INFO - PROCESS | 31505 | Values: 1561.4 1591.3 1781.4 1870.9 1874.7 8742.6 9311.6 9491.3 10118.3 10130.7 9523.7 9820.2 9826.5 9830.0 9830.9 2502.0 2558.4 2560.9 2565.1 2568.3 2465.5 2529.4 2537.9 2538.9 2545.1 2519.6 2526.8 2548.9 2555.9 2557.6 2468.3 2485.3 2488.7 2490.7 2491.6 2398.3 2403.5 2410.0 2411.9 2446.5 9889.0 10015.8 10030.3 10044.5 10049.3 5653.9 5803.3 5810.0 5825.2 5844.8 3590.2 3694.2 3701.2 3749.0 3773.2 1249.8 1252.6 1314.4 1317.6 1336.6 13468.7 13551.2 13655.2 13919.9 13923.2 4917.5 4940.1 5171.7 5172.5 5188.1 11539.7 11596.1 11600.8 11613.1 11628.0 5868.8 5891.7 5896.4 5898.5 5911.3 4701.2 5238.8 5356.4 5365.4 5397.1 4268.1 4377.6 4410.5 4419.7 4441.6 1308.0 1323.4 1329.1 1353.2 1379.5 1008.4 1085.5 1106.8 1110.6 1121.6 6661.3 6678.2 6776.0 6802.6 6845.3 496.9 504.1 516.9 517.6 521.2 4685.3 4720.5 4757.2 4804.8 4814.6 11688.3 12037.2 12113.9 12116.1 12136.2 11522.5 11900.8 11911.5 11984.3 12021.8 9007.3 9136.2 9173.6 9186.7 9247.3 6673.1 6698.2 6745.9 6752.5 6892.6 10675.3 10890.1 10912.9 10983.7 11251.1 11208.7 11220.9 11234.2 11283.5 11338.1 8792.9 8835.1 8978.0 8991.3 9015.8 9451.5 9455.3 9532.7 9543.3 9639.2 8272.4 8403.8 8490.7 8491.7 8673.0 8180.6 8271.1 8279.7 8309.1 8449.9 7793.3 7889.2 7890.4 7897.4 7903.7 5866.8 5881.6 5981.6 5984.9 6055.6 5961.3 6025.4 6026.1 6112.1 6156.8 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#4] prototype.html Cycles:180 Average:4935.47 Median:5470.47 stddev:1427.97 (26.1%) stddev-sans-first:1389.52 19:28:32 INFO - PROCESS | 31505 | Values: 330.4 354.1 360.1 365.2 369.6 5691.3 5783.2 5869.5 5921.9 5941.7 5755.7 5756.9 5780.6 5786.6 5823.0 4492.8 4512.5 4521.4 4530.2 4531.7 4452.8 4545.8 4548.9 4597.1 4603.7 4500.3 4509.4 4522.4 4535.5 4538.8 4525.2 4535.2 4548.6 4559.1 4564.7 3887.6 4026.9 4031.3 4035.8 4073.2 5626.4 5637.9 5659.1 5675.5 5689.8 5256.6 5268.1 5281.9 5305.5 5306.8 5787.7 6027.6 6035.3 6048.2 6200.8 4351.5 4373.4 4386.7 4389.3 4408.0 6147.1 6363.9 6372.5 6410.0 6426.4 4419.5 4433.3 4436.1 4446.8 4449.2 4532.4 4548.2 4551.8 4553.5 4558.6 5119.7 5184.4 5198.3 5208.3 5213.3 5791.1 6035.1 6067.4 6080.5 6099.8 1513.6 1551.6 1564.3 1565.2 1568.7 964.2 969.3 972.9 991.9 996.8 5084.4 5161.1 5161.5 5185.3 5192.3 5435.5 5452.7 5477.0 5499.1 5508.8 5028.1 5191.8 5209.0 5257.3 5259.7 2954.3 2956.1 2956.5 2971.5 2978.6 5522.9 5582.7 5583.7 5598.3 5602.9 5184.4 5512.8 5529.3 5547.8 5553.8 5522.2 5575.0 5583.7 5602.9 5630.5 5125.7 5444.8 5454.9 5473.9 5527.5 5378.5 5384.8 5388.0 5429.9 5467.0 5521.8 5651.6 5660.4 5688.9 5733.8 4084.2 4118.6 4140.5 4461.3 4497.6 6178.4 6274.3 6279.5 6290.5 6336.4 6174.7 6183.2 6186.3 6187.9 6205.8 6031.7 6123.7 6148.6 6174.0 6181.2 6098.8 6121.1 6130.7 6152.4 6174.1 6228.3 6230.3 6279.4 6280.5 6301.4 5784.7 6016.7 6070.0 6085.2 6144.2 19:28:32 INFO - PROCESS | 31505 | 19:28:32 INFO - PROCESS | 31505 | [#5] yui.html Cycles:180 Average:21874.28 Median:4227.36 stddev:30631.11 (724.6%) stddev-sans-first:30676.15 19:28:32 INFO - PROCESS | 31505 | Values: 794.9 800.0 839.7 883.3 891.8 14455.3 15541.5 16385.3 16407.1 16413.6 17566.9 18211.0 18225.5 18339.3 18399.2 843.1 859.5 860.4 867.5 870.3 856.8 870.3 874.7 877.1 885.5 930.2 934.3 935.6 936.7 937.1 919.7 920.0 921.1 924.4 929.0 676.8 679.1 681.8 682.9 687.9 13416.2 13498.9 13562.6 13605.3 13645.3 15664.3 15846.4 15929.7 15984.8 16010.4 13071.3 13357.3 13519.5 13534.5 13642.5 4182.7 4204.7 4210.4 4244.3 4267.5 89533.1 91136.0 91353.5 91358.5 93207.5 11765.7 11983.3 12019.3 12400.9 12510.1 22319.4 23728.0 23741.5 23744.5 23750.9 9549.0 9581.9 9593.0 9603.0 9959.5 5205.2 5250.3 5265.6 5309.2 5362.0 3880.3 3997.3 4007.4 4018.8 4034.3 666.0 670.4 672.9 695.3 698.2 759.7 765.8 777.7 795.6 796.3 10046.1 10047.1 10083.3 10185.3 10222.2 673.0 730.4 808.3 816.9 831.9 4462.7 4645.3 4708.0 4738.1 4780.1 89429.6 109253.5 110993.0 113338.4 114850.0 96608.5 97812.0 100226.5 100449.5 103851.0 84664.6 87946.6 91917.0 95504.5 97131.5 9313.0 9440.8 9515.7 9548.8 9621.6 38843.0 48701.8 48809.3 50085.5 52379.7 75843.6 78608.6 78725.0 82608.0 83407.6 14146.5 14455.7 14515.4 14573.3 15246.3 15595.7 15603.8 15604.7 16924.2 16954.7 15016.5 15601.6 15841.9 16236.9 16860.2 13977.7 14905.8 15079.1 15571.8 16121.6 14801.0 15132.5 15200.5 15448.2 15582.5 12353.4 12427.0 12713.2 12851.7 13327.4 11700.5 11876.5 13401.3 13533.9 13598.9 19:28:32 INFO - PROCESS | 31505 | -------- Summary: end -------- 19:28:32 INFO - PROCESS | 31505 | 19:28:33 INFO - TEST-INFO | 31505: exit 0 19:28:33 INFO - TEST-OK | dromaeo_css | took 1110288ms 19:28:33 INFO - TEST-START | kraken 19:28:33 INFO - operating with platform_type : linux_ 19:28:33 INFO - Initialising browser for kraken test... 19:28:33 INFO - TEST-INFO | started process 31775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU0kwyp/profile http://localhost:54592/getInfo.html) 19:28:37 INFO - PROCESS | 31775 | __metrics Screen width/height:1600/1200 19:28:37 INFO - PROCESS | 31775 | colorDepth:24 19:28:37 INFO - PROCESS | 31775 | Browser inner width/height: 1024/697 19:28:37 INFO - PROCESS | 31775 | __metrics 19:28:38 ERROR - PROCESS | 31775 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 19:28:39 INFO - TEST-INFO | 31775: exit 0 19:28:39 INFO - Browser initialized. 19:28:39 INFO - Running cycle 1/1 for kraken test... 19:28:39 INFO - TEST-INFO | started process 31899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU0kwyp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:51 INFO - PROCESS | 31899 | RSS: Main: 152719360 19:28:51 INFO - PROCESS | 31899 | 19:28:53 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54592/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:54 INFO - PROCESS | 31899 | RSS: Main: 157675520 19:28:54 INFO - PROCESS | 31899 | 19:28:56 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54592/tests/kraken/driver.html?testName=audio-dft) 19:28:57 INFO - PROCESS | 31899 | RSS: Main: 156200960 19:28:57 INFO - PROCESS | 31899 | 19:29:00 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54592/tests/kraken/driver.html?testName=audio-fft) 19:29:01 INFO - PROCESS | 31899 | RSS: Main: 157368320 19:29:01 INFO - PROCESS | 31899 | 19:29:03 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54592/tests/kraken/driver.html?testName=audio-oscillator) 19:29:03 INFO - PROCESS | 31899 | RSS: Main: 156905472 19:29:03 INFO - PROCESS | 31899 | 19:29:05 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54592/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:06 INFO - PROCESS | 31899 | RSS: Main: 156545024 19:29:06 INFO - PROCESS | 31899 | 19:29:09 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54592/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:10 INFO - PROCESS | 31899 | RSS: Main: 156336128 19:29:10 INFO - PROCESS | 31899 | 19:29:13 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54592/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:14 INFO - PROCESS | 31899 | RSS: Main: 156848128 19:29:14 INFO - PROCESS | 31899 | 19:29:17 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54592/tests/kraken/driver.html?testName=json-parse-financial) 19:29:17 INFO - PROCESS | 31899 | RSS: Main: 157159424 19:29:17 INFO - PROCESS | 31899 | 19:29:19 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54592/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:19 INFO - PROCESS | 31899 | RSS: Main: 156385280 19:29:19 INFO - PROCESS | 31899 | 19:29:21 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:22 INFO - PROCESS | 31899 | RSS: Main: 156217344 19:29:22 INFO - PROCESS | 31899 | 19:29:24 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:24 INFO - PROCESS | 31899 | RSS: Main: 156278784 19:29:24 INFO - PROCESS | 31899 | 19:29:27 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:28 INFO - PROCESS | 31899 | RSS: Main: 157298688 19:29:28 INFO - PROCESS | 31899 | 19:29:30 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:31 INFO - PROCESS | 31899 | RSS: Main: 157343744 19:29:31 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | Cycle 1(1): loaded http://localhost:54592/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54592/tests/kraken/driver.html?testName=ai-astar) 19:29:33 INFO - PROCESS | 31899 | RSS: Main: 158617600 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | __start_tp_report 19:29:33 INFO - PROCESS | 31899 | _x_x_mozilla_page_load 19:29:33 INFO - PROCESS | 31899 | _x_x_mozilla_page_load_details 19:29:33 INFO - PROCESS | 31899 | |i|pagename|runs| 19:29:33 INFO - PROCESS | 31899 | |0;ai-astar;101;110;105;111;110;113;111;110;109;109 19:29:33 INFO - PROCESS | 31899 | |1;audio-beat-detection;146;117;143;168;120;148;147;116;164;119 19:29:33 INFO - PROCESS | 31899 | |2;audio-dft;161;162;163;162;160;161;160;162;161;161 19:29:33 INFO - PROCESS | 31899 | |3;audio-fft;75;75;77;170;77;75;74;75;180;75 19:29:33 INFO - PROCESS | 31899 | |4;audio-oscillator;84;83;136;83;83;83;141;84;82;151 19:29:33 INFO - PROCESS | 31899 | |5;imaging-gaussian-blur;118;117;120;117;117;118;118;118;117;117 19:29:33 INFO - PROCESS | 31899 | |6;imaging-darkroom;147;147;147;147;147;147;147;148;147;147 19:29:33 INFO - PROCESS | 31899 | |7;imaging-desaturate;95;95;96;97;96;95;95;96;95;95 19:29:33 INFO - PROCESS | 31899 | |8;json-parse-financial;55;53;55;54;55;56;55;55;53;53 19:29:33 INFO - PROCESS | 31899 | |9;json-stringify-tinderbox;62;60;60;61;61;60;60;60;60;61 19:29:33 INFO - PROCESS | 31899 | |10;stanford-crypto-aes;74;82;73;73;73;78;73;73;73;73 19:29:33 INFO - PROCESS | 31899 | |11;stanford-crypto-ccm;130;126;132;121;137;118;120;119;160;120 19:29:33 INFO - PROCESS | 31899 | |12;stanford-crypto-pbkdf2;157;154;154;153;151;155;154;154;151;155 19:29:33 INFO - PROCESS | 31899 | |13;stanford-crypto-sha256-iterative;61;67;64;62;64;61;61;61;60;64 19:29:33 INFO - PROCESS | 31899 | __end_tp_report 19:29:33 INFO - PROCESS | 31899 | __start_cc_report 19:29:33 INFO - PROCESS | 31899 | _x_x_mozilla_cycle_collect,884 19:29:33 INFO - PROCESS | 31899 | __end_cc_report 19:29:33 INFO - PROCESS | 31899 | __startTimestamp1471055373332__endTimestamp 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | ------- Summary: start ------- 19:29:33 INFO - PROCESS | 31899 | Number of tests: 14 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:3.45 (3.1%) stddev-sans-first:2.17 19:29:33 INFO - PROCESS | 31899 | Values: 101.0 110.0 105.0 111.0 110.0 113.0 111.0 110.0 109.0 109.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:146.50 stddev:19.58 (13.4%) stddev-sans-first:20.59 19:29:33 INFO - PROCESS | 31899 | Values: 146.0 117.0 143.0 168.0 120.0 148.0 147.0 116.0 164.0 119.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:0.95 (0.6%) stddev-sans-first:1.00 19:29:33 INFO - PROCESS | 31899 | Values: 161.0 162.0 163.0 162.0 160.0 161.0 160.0 162.0 161.0 161.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#3] audio-fft Cycles:10 Average:95.30 Median:75.00 stddev:42.08 (56.1%) stddev-sans-first:43.99 19:29:33 INFO - PROCESS | 31899 | Values: 75.0 75.0 77.0 170.0 77.0 75.0 74.0 75.0 180.0 75.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#4] audio-oscillator Cycles:10 Average:101.00 Median:83.00 stddev:28.98 (34.9%) stddev-sans-first:30.08 19:29:33 INFO - PROCESS | 31899 | Values: 84.0 83.0 136.0 83.0 83.0 83.0 141.0 84.0 82.0 151.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 19:29:33 INFO - PROCESS | 31899 | Values: 118.0 117.0 120.0 117.0 117.0 118.0 118.0 118.0 117.0 117.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 19:29:33 INFO - PROCESS | 31899 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 147.0 148.0 147.0 147.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 19:29:33 INFO - PROCESS | 31899 | Values: 95.0 95.0 96.0 97.0 96.0 95.0 95.0 96.0 95.0 95.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:1.12 19:29:33 INFO - PROCESS | 31899 | Values: 55.0 53.0 55.0 54.0 55.0 56.0 55.0 55.0 53.0 53.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:0.71 (1.2%) stddev-sans-first:0.50 19:29:33 INFO - PROCESS | 31899 | Values: 62.0 60.0 60.0 61.0 61.0 60.0 60.0 60.0 60.0 61.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:73.00 stddev:3.06 (4.2%) stddev-sans-first:3.24 19:29:33 INFO - PROCESS | 31899 | Values: 74.0 82.0 73.0 73.0 73.0 78.0 73.0 73.0 73.0 73.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#11] stanford-crypto-ccm Cycles:10 Average:128.30 Median:128.00 stddev:12.85 (10.0%) stddev-sans-first:13.61 19:29:33 INFO - PROCESS | 31899 | Values: 130.0 126.0 132.0 121.0 137.0 118.0 120.0 119.0 160.0 120.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.80 Median:154.00 stddev:1.81 (1.2%) stddev-sans-first:1.51 19:29:33 INFO - PROCESS | 31899 | Values: 157.0 154.0 154.0 153.0 151.0 155.0 154.0 154.0 151.0 155.0 19:29:33 INFO - PROCESS | 31899 | 19:29:33 INFO - PROCESS | 31899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:63.00 stddev:2.17 (3.4%) stddev-sans-first:2.24 19:29:33 INFO - PROCESS | 31899 | Values: 61.0 67.0 64.0 62.0 64.0 61.0 61.0 61.0 60.0 64.0 19:29:33 INFO - PROCESS | 31899 | -------- Summary: end -------- 19:29:33 INFO - PROCESS | 31899 | 19:29:34 INFO - TEST-INFO | 31899: exit 0 19:29:34 INFO - TEST-OK | kraken | took 60947ms 19:29:34 INFO - SUITE-END | took 1171s 19:29:34 INFO - Completed test suite (00:19:31) 19:29:34 INFO - javascript benchmark 19:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7698.947849170334, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.0, 3333.133385319816, 3338.2155193529516, 3349.1459677782163, 3364.6635336466356, 5826.533877289818, 5848.4736373726355, 5851.9999999999945, 5854.88290234195, 5917.49701275391, 5740.512056475204, 5811.767529298823, 5839.854003649911, 5862.824115276538, 5870.383609720971, 5243.475652434764, 5269.65747226431, 5309.150535914235, 5325.733713314338, 5343.919841202385, 5225.895482090375, 5229.947700522984, 5256.185291279858, 5263.26314315996, 5297.496737809902, 5276.076686579833, 5288.788448462057, 5289.471052894699, 5351.892962140755, 5393.083175860113, 5315.495027972336, 5318.0, 5338.0925242708445, 5341.545968592674, 5357.544608708265, 5075.5178258065425, 5133.974330128325, 5152.896942061137, 5245.213218017289, 5286.259923610698, 5665.206871038058, 5721.256236689203, 5722.885542289131, 5729.799457018984, 5768.798092066758, 6128.0, 6131.926912790288, 6135.171751813493, 6141.078838174265, 6143.600665956743, 6102.115193296947, 6134.283518479682, 6209.16176316196, 6212.409821067036, 6231.252249730016, 5769.88460230793, 5825.271841019872, 5840.0, 5879.441453061952, 5893.79371721992, 6365.395287447685, 6383.4574061204385, 6388.041793730885, 6403.039544068427, 6405.711742971577, 5904.763809447574, 6012.849678757979, 6077.574569780161, 6101.633901965896, 6138.600990935575, 6111.419415155553, 6207.96896015517, 6212.4408803207925, 6240.65676387794, 6242.65665388408, 6135.631862088199, 6184.0, 6211.0, 6215.658138802319, 6216.564840461125, 6028.0355143176885, 6037.275526936795, 6190.1952746142715, 6198.597091189148, 6207.844803879939, 4665.323528088476, 4698.083873644607, 4706.741129237925, 4733.905321893543, 4737.336772851803, 4167.1040726244, 4172.08214192877, 4176.519700234512, 4213.241616508996, 4218.0, 5763.625364351304, 5806.767729290781, 5845.532357411397, 5852.94147058524, 5882.235309409835, 6106.694665266719, 6157.630542167484, 6173.783917562864, 6194.783182588588, 6199.411055949678, 5877.265341832271, 5877.265341832271, 5898.557608179403, 5906.793262235801, 5938.2280303560265, 5261.684298942076, 5285.735713214401, 5307.734613269321, 5309.41596424393, 5316.255724198615, 6348.936510634927, 6458.935410645928, 6460.903086453707, 6467.870642587122, 6484.513661475408, 6166.414190651881, 6194.752209911553, 6205.2553693557065, 6230.470410015108, 6250.124982502544, 6166.845828854314, 6285.528585356025, 6314.368563143833, 6452.709628066842, 6465.321141280097, 6116.214037636738, 6127.433875357673, 6165.0, 6171.290301615192, 6178.969105154445, 6224.315325314121, 6246.46905013079, 6257.186565746424, 6262.937370626236, 6268.310485846462, 5942.851428714317, 6017.789377371771, 6020.307664618625, 6113.51091912637, 6119.112728654409, 5989.880202396015, 6004.219451471281, 6030.969845150922, 6082.239720034996, 6103.0, 5958.731857066356, 5981.282246130492, 5983.072623743326, 6023.427774361428, 6023.969880150571, 6007.0, 6089.1779609753885, 6113.749968914014, 6119.571629985859, 6151.753929842756, 6022.45797878193, 6043.697815109139, 6063.848403789941, 6071.51427885777, 6075.817725468243], "value": 5711.522803369363, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.3363099697341, 627.780276903084, 653.4837478392069, 654.744649586661, 684.1584850633724, 39946.60053399465, 44494.55505444945, 44584.10831783363, 44677.32984005242, 44700.0, 44663.77668111663, 45211.32183017257, 45268.3209751854, 45305.0, 45322.32016519747, 9083.137101975319, 9286.767830804216, 9303.162715355616, 9307.069293070706, 9311.813763724722, 9170.44977301364, 9264.212541933945, 9296.535173241346, 9328.300377471684, 9350.766230844249, 9274.165325120737, 9298.86051709225, 9315.115064068936, 9334.953325233364, 9385.906140938605, 9323.440593564408, 9343.252539796835, 9351.158395744347, 9367.484788336638, 9371.250299975985, 9259.166674999282, 9272.443653380751, 9273.07269273074, 9283.58223879927, 9298.302627302912, 42366.0, 42509.36235956463, 42717.14565708699, 42997.92505187364, 43001.1399772006, 3109.4713898637287, 3765.7928813915223, 3822.9426558601645, 3871.9225615487812, 4388.561143885618, 31876.362472750414, 32588.0, 32832.83583582091, 32869.0, 33266.50100248498, 4518.502964673883, 4563.908721825578, 4630.421197350332, 4674.415698037746, 4727.101850648366, 297685.0, 297778.0, 298143.99999999785, 298186.0, 298715.99999999785, 38213.23573528569, 38374.424383634265, 38518.22963540714, 38588.61411385906, 38674.41988370177, 38447.80776096102, 39415.211695765924, 39501.0, 40725.59274407218, 40873.386899196536, 18173.0, 18673.719894201597, 18722.344717934808, 18752.81247187538, 18802.247910083715, 14882.702345953237, 14896.925515372353, 14931.552053438414, 14984.325705343068, 15019.09885406879, 20512.487187820425, 20666.17335306601, 20779.376618701463, 20817.791822081585, 20885.37343879686, 7372.778816635509, 7652.196519365498, 7739.226077392215, 7743.4966727162555, 7751.806204844924, 1025.6974192613197, 1130.5082289204058, 1192.713748700323, 1199.7600479904054, 1209.776191404593, 31711.0, 32121.678783211868, 32555.34889302201, 32656.83671581627, 32911.835440823124, 1088.6733979806186, 1108.6784832398514, 1120.7590368070903, 1150.4880328254014, 1166.1661911733152, 17380.3047878086, 17460.476185714448, 17626.118694065502, 17668.91165544164, 17679.73480397795, 429800.0, 432086.0, 434788.0, 435795.0, 438045.0, 57784.0, 58053.419465805644, 58258.70870645619, 58368.7081564598, 58535.707321463124, 1446.2696338349067, 1453.6656568989192, 1476.438953197778, 1480.3338497676089, 1480.9851901480847, 30448.695513044586, 31232.531512026882, 31526.0, 31757.948984539296, 32146.67853321437, 53032.73483632557, 53626.73186634042, 53635.0, 53673.19490207572, 53674.73162634162, 377060.0, 377415.0, 377973.0, 378041.0, 378966.0, 30108.69891301059, 30137.698623014367, 30312.393752125274, 30573.54139687862, 30589.0, 48825.75587122042, 48942.75528622334, 49181.75409122931, 49342.25986610132, 50889.745551272004, 41395.0, 41505.1698966025, 41527.0, 42094.15811683688, 43715.12569748651, 42249.366259505514, 42436.575634243265, 42498.575014249465, 43516.0, 43560.564394356916, 38928.0, 42339.364909527, 44207.0, 44208.115837682424, 46555.767221163675, 36123.63876361308, 38360.0, 38458.0, 38612.80693596626, 38725.6127438722, 26767.464650707265, 27048.72951270541, 27250.182494525197, 27318.863405682845, 27456.725432745417], "value": 20160.139137583883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.6681687832856, 416.4648426771597, 416.6229562246165, 420.24775651583667, 425.4065578517968, 19344.709829352552, 20764.896175519138, 20898.477538061536, 21770.89114554429, 21845.235416760417, 20724.27465038724, 20799.376018719424, 20969.895150524226, 21205.787942120573, 21439.356819295444, 5785.537157027438, 5993.370696076914, 6027.156198132265, 6036.5170786336985, 6044.455998960093, 5969.940300596982, 5970.462658360747, 5974.163617093611, 6002.459778619924, 6023.879522409571, 5777.0, 5916.408359164071, 5933.495652869511, 5952.0, 5981.671008094575, 5881.823545293648, 5907.616004959686, 5928.822135335969, 5933.851653708649, 5957.910631340534, 5343.839684809462, 5359.517643412073, 5369.5972802040005, 5372.274742909735, 5379.085555455582, 22451.438714032116, 23197.0, 24040.519189616283, 24583.13959011444, 24645.753542464525, 2394.31761947844, 2438.756124387565, 2439.6340548917806, 2465.963010554843, 2485.005997600956, 11031.393273369962, 11093.27893686916, 11137.610183643534, 11225.60710375133, 11436.828447573293, 2046.9590608187902, 2073.6889466580133, 2076.8753874767563, 2086.0925497408525, 2189.2157891318684, 86306.5684671579, 86931.56534217288, 87542.0, 87795.56102219512, 88393.0, 9163.725088247364, 9327.813443731087, 9942.80114397708, 11524.711882203012, 11863.58477453285, 16588.336466541205, 16707.749383759252, 16709.0, 16716.24776885043, 16793.244304006348, 8762.342824288204, 8983.910160898307, 8986.370954033286, 9123.042080581443, 9255.352125351295, 8781.956090219635, 8794.780130496662, 8929.508877011696, 8932.508712020772, 9052.366334356531, 4592.425946756655, 4698.600618947427, 5040.722760248222, 5053.823116190915, 5107.080725469451, 2053.188990348804, 2136.6474531702406, 2145.978540214609, 2162.426956856434, 2164.188429338998, 1002.3384566186282, 1004.8794144702682, 1086.266769930294, 1088.3034857691084, 1095.2606990281529, 20750.377488675364, 20916.686249706265, 20978.580428391346, 21018.47453813667, 21244.468888277606, 1051.542578978147, 1059.4755595980039, 1068.9358638481715, 1073.9248252622397, 1114.1309778372881, 11634.0, 11663.941680291544, 11758.882411175779, 11902.940485297519, 12089.879101209051, 469377.0, 469616.0, 470214.0, 478660.0, 495356.0, 93124.0, 93133.53433232791, 93360.47213533575, 98428.50785746025, 100935.49532252438, 1054.5465449856636, 1091.1925175370168, 1104.5305745058317, 1106.618216715232, 1111.2776695148057, 18246.270149194414, 18255.726164107284, 18510.537236568656, 18978.810211897704, 19299.324523641604, 40019.0, 40607.18785624212, 43843.78078109589, 44353.55646443494, 44548.0, 59709.40290597038, 63256.0, 64018.0, 64464.677676613195, 64671.0, 8693.869591956252, 8889.73330800077, 8928.15182557656, 8972.0, 9150.450972941644, 10545.841812372973, 13474.79787803164, 13487.05590608648, 13635.181889086687, 13724.245166515937, 12053.337066461077, 12123.818142728043, 12633.052521060774, 12647.304398258184, 12809.0, 12048.337341446304, 12201.755964880831, 12229.14395992272, 12355.444005019615, 12608.0, 12639.87360126387, 12644.178128421623, 12694.428750706425, 12849.614511564669, 13107.082504224994, 8603.09667484905, 8906.95546522289, 9057.411268267542, 12550.30973296441, 13139.0, 12256.264624122581, 12518.0, 13039.674008149872, 13067.738645227233, 13209.933950330187], "value": 9786.656766759317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.3988614383463, 1591.2839222349937, 1781.447751197129, 1870.8783929044619, 1874.6625607390665, 8742.562871856413, 9311.627534898604, 9491.288153388488, 10118.291719579624, 10130.746731331712, 9523.714288571337, 9820.214382849374, 9826.459544725038, 9830.016998300167, 9830.852537211947, 2501.974980250197, 2558.373198566354, 2560.8591527465983, 2565.076572433922, 2568.3322336192537, 2465.506898620283, 2529.3676580854785, 2537.8857951392138, 2538.9111381101666, 2545.147375629157, 2519.6472493850874, 2526.7725904668578, 2548.872556372174, 2555.948881022388, 2557.552428325045, 2468.2841975826987, 2485.2668462803517, 2488.688913885764, 2490.7011158660985, 2491.6262560615874, 2398.3404563744934, 2403.471236328005, 2410.0480310277517, 2411.9276421707377, 2446.4862378900452, 9888.95055524725, 10015.849762253572, 10030.297879148462, 10044.547995340226, 10049.346792458467, 5653.886922261532, 5803.303603567599, 5810.041343178371, 5825.15535247391, 5844.795432159896, 3590.1922067534856, 3694.2426802505693, 3701.1857391373856, 3749.043993781568, 3773.1699026214187, 1249.8125281207892, 1252.6492582077033, 1314.4479318686174, 1317.5915466205506, 1336.6324260828253, 13468.663283417993, 13551.186928784304, 13655.248961307125, 13919.930400348036, 13923.164610123426, 4917.533747535979, 4940.135476291634, 5171.663841850268, 5172.508611681884, 5188.11801993662, 11539.653810385702, 11596.072314214842, 11600.76798464026, 11613.070954323633, 11628.0, 5868.765249390062, 5891.675957822275, 5896.439838215363, 5898.498627616701, 5911.320198177179, 4701.247800351927, 5238.842834714965, 5356.4107948125675, 5365.356157261075, 5397.082495975694, 4268.125034367947, 4377.606015458624, 4410.470743510735, 4419.734815911055, 4441.578050085237, 1307.9738405232033, 1323.3912400295778, 1329.0962145740796, 1353.2151352215692, 1379.5171689908452, 1008.3949630221808, 1085.5169449594855, 1106.8118419868642, 1110.5946329589642, 1121.6466812953893, 6661.26726060133, 6678.198616165994, 6776.0, 6802.625855577891, 6845.349691779273, 496.8956519130951, 504.1052132464831, 516.8862850172956, 517.6014468858957, 521.1661341853005, 4685.320628508847, 4720.504347043579, 4757.191277482836, 4804.807807687724, 4814.566688998007, 11688.298702077902, 12037.157398981988, 12113.939430302968, 12116.09129315305, 12136.150469467053, 11522.539098436137, 11900.76198476043, 11911.52353905834, 11984.34086125254, 12021.819672704916, 9007.279417646572, 9136.177744003071, 9173.633054677872, 9186.678466253648, 9247.306452016126, 6673.099131617169, 6698.196216454056, 6745.898811517733, 6752.459803215829, 6892.620905850025, 10675.306105103144, 10890.128789696964, 10912.945435272773, 10983.725406864893, 11251.099912006855, 11208.719782005515, 11220.943895280472, 11234.157438192005, 11283.492242849255, 11338.14963877696, 8792.912070879209, 8835.07231740658, 8978.0, 8991.325650576364, 9015.81968360616, 9451.480168590793, 9455.338126331091, 9532.666356677482, 9543.33196676226, 9639.180669642878, 8272.420930535045, 8403.83192336162, 8490.702825401082, 8491.74524764258, 8672.956635216784, 8180.631871565674, 8271.131531189138, 8279.668813247403, 8309.127541608044, 8449.915500844912, 7793.337566306701, 7889.171636978034, 7890.368770498231, 7897.368210543028, 7903.723369682033, 5866.823995280149, 5881.617694849821, 5981.641101533922, 5984.8803023940145, 6055.636661800306, 5961.344252132349, 6025.367336429787, 6026.096085587197, 6112.144299798121, 6156.78451254204], "value": 5052.19396518809, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.3755901346454, 354.1094148217234, 360.10513873025525, 365.24394503378016, 369.6100613852386, 5691.260136182301, 5783.2481777368985, 5869.471747542721, 5921.911171332434, 5941.673207973565, 5755.740991655375, 5756.856078598037, 5780.624259423134, 5786.594938354307, 5823.0, 4492.752898590568, 4512.548745125494, 4521.367008618796, 4530.165283060366, 4531.663159367978, 4452.8441504547345, 4545.8408955686555, 4548.9090218195615, 4597.149527337453, 4603.677742558022, 4500.347449619803, 4509.436320459942, 4522.4346956630425, 4535.546445355454, 4538.841140560081, 4525.185466616008, 4535.22901106811, 4548.636109111296, 4559.110973360196, 4564.657650676179, 3887.5529314128953, 4026.879193624196, 4031.3348297530874, 4035.778032208227, 4073.1853629274265, 5626.381098079208, 5637.887242255173, 5659.122835960433, 5675.5459563234845, 5689.829305120853, 5256.632035757499, 5268.130758424855, 5281.86795330116, 5305.4614161893005, 5306.815910453697, 5787.681677507735, 6027.578069535134, 6035.275766907999, 6048.153258543807, 6200.81397558074, 4351.477822661269, 4373.431453911003, 4386.6929314947965, 4389.31965545341, 4407.955920440819, 6147.139400483891, 6363.872722545523, 6372.522060845455, 6409.9679501602195, 6426.389492998158, 4419.513853476115, 4433.334999749999, 4436.112777444524, 4446.820523325838, 4449.1991441540185, 4532.4334458192725, 4548.248924164221, 4551.7724113794175, 4553.476350219701, 4558.5897269245925, 5119.692818430906, 5184.351956005499, 5198.298229739013, 5208.348956380452, 5213.296205012351, 5791.102379131242, 6035.124906888476, 6067.362926892701, 6080.543959203077, 6099.817005489843, 1513.6291608555973, 1551.5965848879148, 1564.3429759501037, 1565.2330358124664, 1568.7019466301367, 964.2189826240767, 969.3021024862088, 972.9443553744267, 991.9156871665845, 996.7807082441851, 5084.3644544431945, 5161.071007218661, 5161.458046905021, 5185.325907631984, 5192.273081768555, 5435.5324062502905, 5452.727363631802, 5476.972615136979, 5499.1476321170285, 5508.779648814083, 5028.094942910238, 5191.844244672666, 5209.0, 5257.316548848626, 5259.715294317321, 2954.335274563249, 2956.1427186116216, 2956.49739544278, 2971.5439434676855, 2978.627671541057, 5522.889542209134, 5582.692951887685, 5583.748731307101, 5598.300212473441, 5602.915956260659, 5184.429712731597, 5512.80705175317, 5529.336479622471, 5547.77808887648, 5553.8333849984565, 5522.22688823565, 5574.972125139429, 5583.69289689071, 5602.915956260659, 5630.5495560355075, 5125.743712814419, 5444.8094316698725, 5454.863628409322, 5473.945260547343, 5527.452313352268, 5378.467136865975, 5384.784608615611, 5388.030154572215, 5429.945700542944, 5467.0, 5521.834344969658, 5651.6043876928225, 5660.433956604306, 5688.919105369966, 5733.770649174153, 4084.1627466368727, 4118.608732170439, 4140.503139623264, 4461.286194208911, 4497.640188784955, 6178.41305076035, 6274.278457977391, 6279.466245369194, 6290.46531044848, 6336.429721325103, 6174.734179493283, 6183.227096612924, 6186.257649082139, 6187.938120618916, 6205.751769929152, 6031.728572214349, 6123.663198524124, 6148.569600127948, 6174.0, 6181.196444462371, 6098.75604975796, 6121.081837724377, 6130.693465326626, 6152.446279834658, 6174.116894347268, 6228.28374736893, 6230.283517395557, 6279.403456671609, 6280.5289603279025, 6301.4328710416285, 5784.727359980798, 6016.729247183975, 6070.0, 6085.208922840003, 6144.201253836973], "value": 4480.3379190551905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.9085855126658, 799.956002419867, 839.7354833227537, 883.3154305413304, 891.8216356728656, 14455.349509272082, 15541.456049038286, 16385.344586216535, 16407.097609631466, 16413.589660258498, 17566.912165439186, 18211.0, 18225.54436139097, 18339.26642934281, 18399.172037258322, 843.0852525010371, 859.5014891363026, 860.4364141487335, 867.5055218525443, 870.2950610005884, 856.7601071699934, 870.2994089757774, 874.6588830356167, 877.079066979672, 885.5173930208019, 930.1721467893547, 934.3039435620473, 935.6304259817368, 936.6955739384694, 937.0816599732267, 919.7056941778634, 919.9908000919972, 921.0927236671906, 924.4314746430964, 929.0384452092086, 676.8409423785406, 679.0628932073756, 681.7613835157655, 682.9282925292872, 687.8555503344304, 13416.195028298333, 13498.865011349957, 13562.59312220635, 13605.251711155883, 13645.317734113254, 15664.295106720225, 15846.366145354172, 15929.681406371808, 15984.76022859658, 16010.439634612732, 13071.346432678329, 13357.265350405723, 13519.526816561372, 13534.458621655122, 13642.522511712043, 4182.749035057906, 4204.663626909871, 4210.389493523453, 4244.3209086546, 4267.487901451846, 89533.10466895312, 91136.0, 91353.54323228408, 91358.54320728421, 93207.53396233043, 11765.705857353463, 11983.28100313984, 12019.33893635841, 12400.937995309965, 12510.124291299522, 22319.44201394978, 23728.0, 23741.52516949651, 23744.525109497714, 23750.88124559366, 9548.952255238679, 9581.904180958241, 9593.0, 9603.0, 9959.452230127268, 5205.2192171173865, 5250.264962905197, 5265.552428043659, 5309.177077552986, 5362.034833729888, 3880.262750077476, 3997.2804895118575, 4007.3788562772816, 4018.778967156776, 4034.2738307105, 666.044226534924, 670.4368330602317, 672.8856094463953, 695.2838576266454, 698.2005603583868, 759.7264984605538, 765.8148219977737, 777.6578305545542, 795.586295126531, 796.279367172698, 10046.095851373411, 10047.095761381364, 10083.344582602107, 10185.287029907984, 10222.182225421948, 672.9831754206184, 730.3755289227634, 808.2765924497659, 816.9223923727236, 831.9417640765209, 4462.6876118672035, 4645.3032045193495, 4707.952920470751, 4738.076075165379, 4780.067886762117, 89429.55285223533, 109253.45373273082, 110993.0, 113338.43330783458, 114850.0, 96608.51695741617, 97812.0, 100226.4988675052, 100449.49775251077, 103851.0, 84664.57667711622, 87946.56026719825, 91917.0, 95504.52247738717, 97131.51434242925, 9313.0, 9440.763980900532, 9515.714528564155, 9548.761280968032, 9621.615135394786, 38843.0, 48701.75649121732, 48809.2678609814, 50085.499145008085, 52379.73810130925, 75843.62078189795, 78608.60695696485, 78725.0, 82608.0, 83407.5829620848, 14146.504872329419, 14455.710885782015, 14515.419383224553, 14573.271336433349, 15246.31391587359, 15595.688086237984, 15603.843961560693, 15604.68790624204, 16924.153792310088, 16954.660906782043, 15016.474423395566, 15601.609959751097, 15841.920790395974, 16236.918815405847, 16860.1569921501, 13977.720445591234, 14905.776413353591, 15079.095254284777, 15571.84428155704, 16121.596960076093, 14801.0, 15132.546023619308, 15200.46798362052, 15448.227588620748, 15582.532524024298, 12353.444095015926, 12427.0, 12713.17364371313, 12851.678708032374, 13327.416242414634, 11700.531978721096, 11876.465559049691, 13401.32993350348, 13533.932330338286, 13598.93200533991], "value": 8163.532000989038, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1499.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 105.0, 111.0, 110.0, 113.0, 111.0, 110.0, 109.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 143.0, 168.0, 120.0, 148.0, 147.0, 116.0, 164.0, 119.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 162.0, 160.0, 161.0, 160.0, 162.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 170.0, 77.0, 75.0, 74.0, 75.0, 180.0, 75.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 136.0, 83.0, 83.0, 83.0, 141.0, 84.0, 82.0, 151.0], "value": 101.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 120.0, 117.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 97.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 54.0, 55.0, 56.0, 55.0, 55.0, 53.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 73.0, 73.0, 73.0, 78.0, 73.0, 73.0, 73.0, 73.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 132.0, 121.0, 137.0, 118.0, 120.0, 119.0, 160.0, 120.0], "value": 128.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 154.0, 154.0, 153.0, 151.0, 155.0, 154.0, 154.0, 151.0, 155.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 64.0, 62.0, 64.0, 61.0, 61.0, 61.0, 60.0, 64.0], "value": 62.5, "unit": "score"}]}]} 19:29:34 INFO - Return code: 0 19:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:29:34 ERROR - # TBPL SUCCESS # 19:29:34 INFO - Running post-action listener: _resource_record_post_action 19:29:34 INFO - [mozharness: 2016-08-13 02:29:34.662825Z] Finished run-tests step (success) 19:29:34 INFO - Running post-run listener: _resource_record_post_run 19:29:34 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327446528; Read time: 16; Write time: 234428 19:29:34 INFO - TinderboxPrint: CPU usage
13.3% 19:29:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:29:34 INFO - TinderboxPrint: I/O write bytes / time
327,446,528 / 234,428 19:29:34 INFO - TinderboxPrint: CPU idle
8,218.2 (86.7%) 19:29:34 INFO - TinderboxPrint: CPU user
1,203.4 (12.7%) 19:29:34 INFO - TinderboxPrint: Swap in / out
0 / 0 19:29:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77086720; Read time: 0; Write time: 42808 19:29:34 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 250089472; Read time: 16; Write time: 191592 19:29:35 INFO - Running post-run listener: _upload_blobber_files 19:29:35 INFO - Blob upload gear active. 19:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:35 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:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:36 INFO - (blobuploader) - INFO - Done attempting. 19:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:40 INFO - (blobuploader) - INFO - Done attempting. 19:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:41 INFO - (blobuploader) - INFO - Done attempting. 19:29:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:41 INFO - Return code: 0 19:29:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217a8771042f77583bd9a83b7e3a010fa5263805d8a7ecf06ec5e82fb116b79445ab3791f9848ac7feea3a864a5110b7f20a529219867c7c39cd99bfc0c69eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df46b6cd611b6601f7d06e90fc300c9df7aa610531fd38e36df09cc4ea9b424c8a755cd99e05fb57bb24970347f1e2c088b5726333d4daf55483ea20ff95d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3c6d36a8e01d9fee5f6d7902b1ff093dc1d226af1617c02546ad9dc0ed32f824b90a419ec14dc0e2ae708d3cc25baefedf58d4802bebb469000a7822a61daf"} 19:29:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:41 INFO - Contents: 19:29:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217a8771042f77583bd9a83b7e3a010fa5263805d8a7ecf06ec5e82fb116b79445ab3791f9848ac7feea3a864a5110b7f20a529219867c7c39cd99bfc0c69eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df46b6cd611b6601f7d06e90fc300c9df7aa610531fd38e36df09cc4ea9b424c8a755cd99e05fb57bb24970347f1e2c088b5726333d4daf55483ea20ff95d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3c6d36a8e01d9fee5f6d7902b1ff093dc1d226af1617c02546ad9dc0ed32f824b90a419ec14dc0e2ae708d3cc25baefedf58d4802bebb469000a7822a61daf"} 19:29:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:41 INFO - Copying logs to upload dir... 19:29:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.561887 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-08-12 19:29:41.606775) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:29:41.611566) ========= 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=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217a8771042f77583bd9a83b7e3a010fa5263805d8a7ecf06ec5e82fb116b79445ab3791f9848ac7feea3a864a5110b7f20a529219867c7c39cd99bfc0c69eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df46b6cd611b6601f7d06e90fc300c9df7aa610531fd38e36df09cc4ea9b424c8a755cd99e05fb57bb24970347f1e2c088b5726333d4daf55483ea20ff95d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3c6d36a8e01d9fee5f6d7902b1ff093dc1d226af1617c02546ad9dc0ed32f824b90a419ec14dc0e2ae708d3cc25baefedf58d4802bebb469000a7822a61daf"} build_url:https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/f5lN1L4oRpCcZujNKRnPHQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/217a8771042f77583bd9a83b7e3a010fa5263805d8a7ecf06ec5e82fb116b79445ab3791f9848ac7feea3a864a5110b7f20a529219867c7c39cd99bfc0c69eb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1df46b6cd611b6601f7d06e90fc300c9df7aa610531fd38e36df09cc4ea9b424c8a755cd99e05fb57bb24970347f1e2c088b5726333d4daf55483ea20ff95d2d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aa3c6d36a8e01d9fee5f6d7902b1ff093dc1d226af1617c02546ad9dc0ed32f824b90a419ec14dc0e2ae708d3cc25baefedf58d4802bebb469000a7822a61daf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 19:29:41.636495) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:29:41.636797) ========= 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=db0483c52d987ecb441a263b000001ad-1471053702.675205-1933299919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 19:29:41.721860) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:29:41.722684) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 19:29:41.723000) ========= ========= Total master_lag: 0.17 =========