builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1475251507.7 results: success (0) buildid: 20160930063003 builduid: 5232fb903bc54f33abfbfbcb0b1057a9 revision: 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.700457) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.701136) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.701409) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.762570) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.762867) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-30 09:05:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 89.8M=0s 2016-09-30 09:05:08 (89.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089723 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.878089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:07.878399) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016811 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.006060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.006375) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 --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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-30 09:05:08,336 truncating revision to first 12 chars 2016-09-30 09:05:08,336 Setting DEBUG logging. 2016-09-30 09:05:08,336 attempt 1/10 2016-09-30 09:05:08,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/659bd1ae3b67?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 09:05:08,669 unpacking tar archive at: autoland-659bd1ae3b67/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686952 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.739269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.739543) ========= script_repo_revision: 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.739908) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.740169) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 09:05:08.815281) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-30 09:05:08.815533) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:05:09 INFO - MultiFileLogger online at 20160930 09:05:09 in /builds/slave/test 09:05:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 09:05:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:09 INFO - {'append_to_log': False, 09:05:09 INFO - 'base_work_dir': '/builds/slave/test', 09:05:09 INFO - 'blob_upload_branch': 'Autoland', 09:05:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:09 INFO - 'branch': 'Autoland', 09:05:09 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:09 INFO - 'config_files': ('talos/linux_config.py',), 09:05:09 INFO - 'default_actions': ('clobber', 09:05:09 INFO - 'read-buildbot-config', 09:05:09 INFO - 'download-and-extract', 09:05:09 INFO - 'populate-webroot', 09:05:09 INFO - 'create-virtualenv', 09:05:09 INFO - 'install', 09:05:09 INFO - 'run-tests'), 09:05:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:09 INFO - 'download_minidump_stackwalk': True, 09:05:09 INFO - 'download_symbols': 'ondemand', 09:05:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:09 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:09 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:09 INFO - 'installer_path': 'installer.exe', 09:05:09 INFO - 'log_level': 'info', 09:05:09 INFO - 'log_name': 'talos', 09:05:09 INFO - 'log_to_console': True, 09:05:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:05:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:05:09 INFO - 'opt_config_files': (), 09:05:09 INFO - 'pip_index': False, 09:05:09 INFO - 'sps_profile': False, 09:05:09 INFO - 'sps_profile_interval': 0, 09:05:09 INFO - 'suite': 'dromaeojs', 09:05:09 INFO - 'system_bits': '32', 09:05:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:09 INFO - 'title': 'talos-linux64-ix-002', 09:05:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:09 INFO - 'use_talos_json': True, 09:05:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:09 INFO - 'work_dir': 'build'} 09:05:09 INFO - [mozharness: 2016-09-30 16:05:09.217479Z] Running clobber step. 09:05:09 INFO - Running pre-action listener: _resource_record_pre_action 09:05:09 INFO - Running main action method: clobber 09:05:09 INFO - rmtree: /builds/slave/test/build 09:05:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:09 INFO - Running post-action listener: _resource_record_post_action 09:05:09 INFO - [mozharness: 2016-09-30 16:05:09.678166Z] Finished clobber step (success) 09:05:09 INFO - [mozharness: 2016-09-30 16:05:09.678266Z] Running read-buildbot-config step. 09:05:09 INFO - Running pre-action listener: _resource_record_pre_action 09:05:09 INFO - Running main action method: read_buildbot_config 09:05:09 INFO - Using buildbot properties: 09:05:09 INFO - { 09:05:09 INFO - "project": "", 09:05:09 INFO - "product": "firefox", 09:05:09 INFO - "script_repo_revision": "production", 09:05:09 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:05:09 INFO - "repository": "", 09:05:09 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 09:05:09 INFO - "buildid": "20160930063003", 09:05:09 INFO - "pgo_build": "True", 09:05:09 INFO - "basedir": "/builds/slave/test", 09:05:09 INFO - "buildnumber": 274, 09:05:09 INFO - "slavename": "talos-linux64-ix-002", 09:05:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:05:09 INFO - "platform": "ubuntu64_hw", 09:05:09 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 09:05:09 INFO - "branch": "autoland", 09:05:09 INFO - "repo_path": "integration/autoland", 09:05:09 INFO - "revision": "659bd1ae3b672329d9fc1e9b655bc02c7ecb8090", 09:05:09 INFO - "stage_platform": "linux64-pgo", 09:05:09 INFO - "builduid": "5232fb903bc54f33abfbfbcb0b1057a9", 09:05:09 INFO - "slavebuilddir": "test" 09:05:09 INFO - } 09:05:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:05:09 INFO - Running post-action listener: _resource_record_post_action 09:05:09 INFO - [mozharness: 2016-09-30 16:05:09.680684Z] Finished read-buildbot-config step (success) 09:05:09 INFO - [mozharness: 2016-09-30 16:05:09.680771Z] Running download-and-extract step. 09:05:09 INFO - Running pre-action listener: _resource_record_pre_action 09:05:09 INFO - Running main action method: download_and_extract 09:05:09 INFO - mkdir: /builds/slave/test/build/tests 09:05:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:09 INFO - trying https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:05:09 INFO - Downloading https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:05:10 INFO - Downloaded 1361 bytes. 09:05:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:05:10 INFO - Using the following test package requirements: 09:05:10 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:05:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:05:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:05:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'jsshell-linux-x86_64.zip'], 09:05:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:05:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:05:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:05:10 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:05:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:05:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:05:10 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:05:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:05:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:05:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:05:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:05:11 INFO - Http code: 200 09:05:11 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 09:05:11 INFO - x-amz-cf-id: 3juc-AQu6mke6Po-7n3yamSFWGNBkrwlM2vQQuEllrXjV5M0n7a4ow== 09:05:11 INFO - x-amz-version-id: TaCerFSoSRhZ0TtXmiCD1bYjlSCUBy9s 09:05:13 INFO - Expected file size: 21989577 09:05:13 INFO - Obtained file size: 21989577 09:05:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:05:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:05:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:05:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:05:17 INFO - Http code: 200 09:05:17 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 09:05:17 INFO - x-amz-cf-id: 7OZSziNUDCW5sXSLd8Affu2IlTVe3nVuEZ_-tJp_6cFepgywjm2VjA== 09:05:17 INFO - x-amz-version-id: TaCerFSoSRhZ0TtXmiCD1bYjlSCUBy9s 09:05:18 INFO - Expected file size: 21989577 09:05:18 INFO - Obtained file size: 21989577 09:05:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:05:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:05:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:05:23 INFO - Http code: 200 09:05:23 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:05:23 INFO - x-amz-cf-id: Kth5Dlb8d67k0P3SmkSiCSTmasKE0z43-MSUY_5hxpRSiZjIO4vMkQ== 09:05:23 INFO - x-amz-version-id: UboWPJpFQ85vLQPIlkSn_CLyDswo5P7s 09:05:24 INFO - Expected file size: 14408522 09:05:24 INFO - Obtained file size: 14408522 09:05:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:24 INFO - trying https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:05:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:05:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:05:27 INFO - Downloaded 59710234 bytes. 09:05:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:05:27 INFO - mkdir: /builds/slave/test/properties 09:05:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:27 INFO - Contents: 09:05:27 INFO - build_url:https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:05:27 INFO - Running post-action listener: _resource_record_post_action 09:05:27 INFO - Running post-action listener: set_extra_try_arguments 09:05:27 INFO - [mozharness: 2016-09-30 16:05:27.982520Z] Finished download-and-extract step (success) 09:05:27 INFO - [mozharness: 2016-09-30 16:05:27.982746Z] Running populate-webroot step. 09:05:27 INFO - Running pre-action listener: _resource_record_pre_action 09:05:27 INFO - Running main action method: populate_webroot 09:05:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:27 INFO - '--tppagecycles', 09:05:27 INFO - '7'], 09:05:27 INFO - 'tests': ['tsvgm']}}, 09:05:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:27 INFO - 'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:27 INFO - 'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['damp', 'tps']}, 09:05:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:27 INFO - 'tests': ['damp', 'tps']}, 09:05:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['dromaeo_dom']}, 09:05:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['basic_compositor_video']}, 09:05:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['a11yr', 09:05:27 INFO - 'ts_paint', 09:05:27 INFO - 'tpaint', 09:05:27 INFO - 'sessionrestore', 09:05:27 INFO - 'sessionrestore_no_auto_restore', 09:05:27 INFO - 'tabpaint']}, 09:05:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:27 INFO - 'ts_paint', 09:05:27 INFO - 'tpaint', 09:05:27 INFO - 'sessionrestore', 09:05:27 INFO - 'sessionrestore_no_auto_restore', 09:05:27 INFO - 'tabpaint']}, 09:05:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:27 INFO - 'ts_paint', 09:05:27 INFO - 'tpaint', 09:05:27 INFO - 'sessionrestore', 09:05:27 INFO - 'sessionrestore_no_auto_restore', 09:05:27 INFO - 'tabpaint']}, 09:05:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:27 INFO - 'ts_paint', 09:05:27 INFO - 'tpaint', 09:05:27 INFO - 'sessionrestore', 09:05:27 INFO - 'sessionrestore_no_auto_restore', 09:05:27 INFO - 'tabpaint']}, 09:05:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:27 INFO - 'tests': ['a11yr', 09:05:27 INFO - 'ts_paint', 09:05:27 INFO - 'tpaint', 09:05:27 INFO - 'sessionrestore', 09:05:27 INFO - 'sessionrestore_no_auto_restore', 09:05:28 INFO - 'tabpaint']}, 09:05:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:28 INFO - 'tests': ['a11yr', 09:05:28 INFO - 'ts_paint', 09:05:28 INFO - 'tpaint', 09:05:28 INFO - 'sessionrestore', 09:05:28 INFO - 'sessionrestore_no_auto_restore', 09:05:28 INFO - 'tabpaint']}, 09:05:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:28 INFO - 'tests': ['tsvgx', 09:05:28 INFO - 'tsvgr_opacity', 09:05:28 INFO - 'tart', 09:05:28 INFO - 'tscrollx', 09:05:28 INFO - 'cart']}, 09:05:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:28 INFO - 'tsvgr_opacity', 09:05:28 INFO - 'tart', 09:05:28 INFO - 'tscrollx', 09:05:28 INFO - 'cart']}, 09:05:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:28 INFO - 'talos_options': ['--disable-e10s'], 09:05:28 INFO - 'tests': ['tp5o']}, 09:05:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:28 INFO - 'tests': ['tp5o']}, 09:05:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:28 INFO - 'talos_options': ['--disable-e10s', 09:05:28 INFO - '--xperf_path', 09:05:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:28 INFO - 'tests': ['tp5n']}, 09:05:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:28 INFO - 'talos_options': ['--xperf_path', 09:05:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:28 INFO - 'tests': ['tp5n']}}, 09:05:28 INFO - 'talos.zip': {'path': '', 09:05:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:28 INFO - Running post-action listener: _resource_record_post_action 09:05:28 INFO - [mozharness: 2016-09-30 16:05:28.002717Z] Finished populate-webroot step (success) 09:05:28 INFO - [mozharness: 2016-09-30 16:05:28.002806Z] Running create-virtualenv step. 09:05:28 INFO - Running pre-action listener: _resource_record_pre_action 09:05:28 INFO - Running main action method: create_virtualenv 09:05:28 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:28 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 09:05:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:28 INFO - Using real prefix '/usr' 09:05:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:30 INFO - Installing pip.................done. 09:05:30 INFO - Return code: 0 09:05:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:05:30 INFO - Reading from file tmpfile_stdout 09:05:30 INFO - Output received: 09:05:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:05:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:05:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:05:30 INFO - Using env: {'DISPLAY': ':0', 09:05:30 INFO - 'HOME': '/home/cltbld', 09:05:30 INFO - 'LANG': 'en_US.UTF-8', 09:05:30 INFO - 'LANGUAGE': 'en_US:en', 09:05:30 INFO - 'LOGNAME': 'cltbld', 09:05:30 INFO - 'MAIL': '/var/mail/cltbld', 09:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:30 INFO - 'MOZ_NO_REMOTE': '1', 09:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:30 INFO - 'NO_EM_RESTART': '1', 09:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:30 INFO - 'PWD': '/builds/slave/test', 09:05:30 INFO - 'SHELL': '/bin/bash', 09:05:30 INFO - 'SHLVL': '1', 09:05:30 INFO - 'TERM': 'linux', 09:05:30 INFO - 'TMOUT': '86400', 09:05:30 INFO - 'USER': 'cltbld', 09:05:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:30 INFO - '_': '/tools/buildbot/bin/python'} 09:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:05:30 INFO - Cleaning up... 09:05:31 INFO - Return code: 0 09:05:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:31 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 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:31 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 09:05:31 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 09:05:31 INFO - Using env: {'DISPLAY': ':0', 09:05:31 INFO - 'HOME': '/home/cltbld', 09:05:31 INFO - 'LANG': 'en_US.UTF-8', 09:05:31 INFO - 'LANGUAGE': 'en_US:en', 09:05:31 INFO - 'LOGNAME': 'cltbld', 09:05:31 INFO - 'MAIL': '/var/mail/cltbld', 09:05:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:31 INFO - 'MOZ_NO_REMOTE': '1', 09:05:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:31 INFO - 'NO_EM_RESTART': '1', 09:05:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:31 INFO - 'PWD': '/builds/slave/test', 09:05:31 INFO - 'SHELL': '/bin/bash', 09:05:31 INFO - 'SHLVL': '1', 09:05:31 INFO - 'TERM': 'linux', 09:05:31 INFO - 'TMOUT': '86400', 09:05:31 INFO - 'USER': 'cltbld', 09:05:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:31 INFO - '_': '/tools/buildbot/bin/python'} 09:05:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:31 INFO - Downloading/unpacking psutil>=3.1.1 09:05:31 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 09:05:31 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 09:05:31 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 09:05:31 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 09:05:31 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 09:05:31 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 09:05:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:33 INFO - Installing collected packages: psutil 09:05:33 INFO - Running setup.py install for psutil 09:05:33 INFO - building 'psutil._psutil_linux' extension 09:05:33 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 09:05:33 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 09:05:33 INFO - building 'psutil._psutil_posix' extension 09:05:33 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 09:05:33 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 09:05:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:33 INFO - Successfully installed psutil 09:05:33 INFO - Cleaning up... 09:05:33 INFO - Return code: 0 09:05:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:05:33 INFO - Using env: {'DISPLAY': ':0', 09:05:33 INFO - 'HOME': '/home/cltbld', 09:05:33 INFO - 'LANG': 'en_US.UTF-8', 09:05:33 INFO - 'LANGUAGE': 'en_US:en', 09:05:33 INFO - 'LOGNAME': 'cltbld', 09:05:33 INFO - 'MAIL': '/var/mail/cltbld', 09:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:33 INFO - 'MOZ_NO_REMOTE': '1', 09:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:33 INFO - 'NO_EM_RESTART': '1', 09:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:33 INFO - 'PWD': '/builds/slave/test', 09:05:33 INFO - 'SHELL': '/bin/bash', 09:05:33 INFO - 'SHLVL': '1', 09:05:33 INFO - 'TERM': 'linux', 09:05:33 INFO - 'TMOUT': '86400', 09:05:33 INFO - 'USER': 'cltbld', 09:05:33 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:33 INFO - '_': '/tools/buildbot/bin/python'} 09:05:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:35 INFO - Installing collected packages: mozsystemmonitor 09:05:35 INFO - Running setup.py install for mozsystemmonitor 09:05:36 INFO - Successfully installed mozsystemmonitor 09:05:36 INFO - Cleaning up... 09:05:36 INFO - Return code: 0 09:05:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:36 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 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:36 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 09:05:36 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 09:05:36 INFO - Using env: {'DISPLAY': ':0', 09:05:36 INFO - 'HOME': '/home/cltbld', 09:05:36 INFO - 'LANG': 'en_US.UTF-8', 09:05:36 INFO - 'LANGUAGE': 'en_US:en', 09:05:36 INFO - 'LOGNAME': 'cltbld', 09:05:36 INFO - 'MAIL': '/var/mail/cltbld', 09:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:36 INFO - 'MOZ_NO_REMOTE': '1', 09:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:36 INFO - 'NO_EM_RESTART': '1', 09:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:36 INFO - 'PWD': '/builds/slave/test', 09:05:36 INFO - 'SHELL': '/bin/bash', 09:05:36 INFO - 'SHLVL': '1', 09:05:36 INFO - 'TERM': 'linux', 09:05:36 INFO - 'TMOUT': '86400', 09:05:36 INFO - 'USER': 'cltbld', 09:05:36 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:36 INFO - '_': '/tools/buildbot/bin/python'} 09:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:05:36 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 09:05:36 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 09:05:36 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 09:05:36 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 09:05:36 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 09:05:36 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 09:05:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:38 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:38 INFO - warning: no files found matching '*.txt' 09:05:38 INFO - no previously-included directories found matching 'build' 09:05:38 INFO - no previously-included directories found matching 'dist' 09:05:38 INFO - no previously-included directories found matching '.git*' 09:05:38 INFO - Installing collected packages: jsonschema, functools32 09:05:38 INFO - Running setup.py install for functools32 09:05:38 INFO - warning: no files found matching '*.txt' 09:05:38 INFO - no previously-included directories found matching 'build' 09:05:38 INFO - no previously-included directories found matching 'dist' 09:05:38 INFO - no previously-included directories found matching '.git*' 09:05:38 INFO - Successfully installed jsonschema functools32 09:05:38 INFO - Cleaning up... 09:05:38 INFO - Return code: 0 09:05:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:38 INFO - Using env: {'DISPLAY': ':0', 09:05:38 INFO - 'HOME': '/home/cltbld', 09:05:38 INFO - 'LANG': 'en_US.UTF-8', 09:05:38 INFO - 'LANGUAGE': 'en_US:en', 09:05:38 INFO - 'LOGNAME': 'cltbld', 09:05:38 INFO - 'MAIL': '/var/mail/cltbld', 09:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:38 INFO - 'MOZ_NO_REMOTE': '1', 09:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:38 INFO - 'NO_EM_RESTART': '1', 09:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:38 INFO - 'PWD': '/builds/slave/test', 09:05:38 INFO - 'SHELL': '/bin/bash', 09:05:38 INFO - 'SHLVL': '1', 09:05:38 INFO - 'TERM': 'linux', 09:05:38 INFO - 'TMOUT': '86400', 09:05:38 INFO - 'USER': 'cltbld', 09:05:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:38 INFO - '_': '/tools/buildbot/bin/python'} 09:05:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:38 INFO - Cleaning up... 09:05:38 INFO - Return code: 0 09:05:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:05:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:05:38 INFO - Using env: {'DISPLAY': ':0', 09:05:38 INFO - 'HOME': '/home/cltbld', 09:05:38 INFO - 'LANG': 'en_US.UTF-8', 09:05:38 INFO - 'LANGUAGE': 'en_US:en', 09:05:38 INFO - 'LOGNAME': 'cltbld', 09:05:38 INFO - 'MAIL': '/var/mail/cltbld', 09:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:38 INFO - 'MOZ_NO_REMOTE': '1', 09:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:38 INFO - 'NO_EM_RESTART': '1', 09:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:38 INFO - 'PWD': '/builds/slave/test', 09:05:38 INFO - 'SHELL': '/bin/bash', 09:05:38 INFO - 'SHLVL': '1', 09:05:38 INFO - 'TERM': 'linux', 09:05:38 INFO - 'TMOUT': '86400', 09:05:38 INFO - 'USER': 'cltbld', 09:05:38 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:38 INFO - '_': '/tools/buildbot/bin/python'} 09:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:39 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:39 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 09:05:39 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 09:05:39 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 09:05:39 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 09:05:39 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 09:05:39 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 09:05:41 INFO - Downloading blobuploader-1.2.4.tar.gz 09:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:05:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:05:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:41 INFO - Downloading docopt-0.6.1.tar.gz 09:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:05:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:05:41 INFO - Running setup.py install for blobuploader 09:05:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:05:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:05:41 INFO - Running setup.py install for requests 09:05:42 INFO - Running setup.py install for docopt 09:05:42 INFO - Successfully installed blobuploader requests docopt 09:05:42 INFO - Cleaning up... 09:05:42 INFO - Return code: 0 09:05:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:42 INFO - Using env: {'DISPLAY': ':0', 09:05:42 INFO - 'HOME': '/home/cltbld', 09:05:42 INFO - 'LANG': 'en_US.UTF-8', 09:05:42 INFO - 'LANGUAGE': 'en_US:en', 09:05:42 INFO - 'LOGNAME': 'cltbld', 09:05:42 INFO - 'MAIL': '/var/mail/cltbld', 09:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:42 INFO - 'MOZ_NO_REMOTE': '1', 09:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:42 INFO - 'NO_EM_RESTART': '1', 09:05:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:42 INFO - 'PWD': '/builds/slave/test', 09:05:42 INFO - 'SHELL': '/bin/bash', 09:05:42 INFO - 'SHLVL': '1', 09:05:42 INFO - 'TERM': 'linux', 09:05:42 INFO - 'TMOUT': '86400', 09:05:42 INFO - 'USER': 'cltbld', 09:05:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:42 INFO - '_': '/tools/buildbot/bin/python'} 09:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:42 INFO - Running setup.py (path:/tmp/pip-IjkLLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:42 INFO - Running setup.py (path:/tmp/pip-n0R90o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:42 INFO - Running setup.py (path:/tmp/pip-Slj87Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:42 INFO - Running setup.py (path:/tmp/pip-PgLhem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:42 INFO - Running setup.py (path:/tmp/pip-G48Jul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:42 INFO - Running setup.py (path:/tmp/pip-P4DF1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:43 INFO - Running setup.py (path:/tmp/pip-C1TgfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:43 INFO - Running setup.py (path:/tmp/pip-HQ4Xc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:43 INFO - Running setup.py (path:/tmp/pip-ixO0H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:43 INFO - Running setup.py (path:/tmp/pip-4RrfPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:43 INFO - Running setup.py (path:/tmp/pip-_ay7OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:43 INFO - Running setup.py (path:/tmp/pip-U6gczA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:43 INFO - Running setup.py (path:/tmp/pip-Sxievr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:43 INFO - Running setup.py (path:/tmp/pip-ZEdJzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:43 INFO - Running setup.py (path:/tmp/pip-o5Jds7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:43 INFO - Running setup.py (path:/tmp/pip-6DGM15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:43 INFO - Running setup.py (path:/tmp/pip-vqT0RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:05:43 INFO - Running setup.py install for manifestparser 09:05:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:05:43 INFO - Running setup.py install for mozcrash 09:05:44 INFO - Running setup.py install for mozdebug 09:05:44 INFO - Running setup.py install for mozdevice 09:05:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for mozfile 09:05:44 INFO - Running setup.py install for mozhttpd 09:05:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for mozinfo 09:05:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for mozInstall 09:05:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for mozleak 09:05:44 INFO - Running setup.py install for mozlog 09:05:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for moznetwork 09:05:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:05:44 INFO - Running setup.py install for mozprocess 09:05:45 INFO - Running setup.py install for mozprofile 09:05:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:05:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:05:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:05:45 INFO - Running setup.py install for mozrunner 09:05:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:05:45 INFO - Running setup.py install for mozscreenshot 09:05:45 INFO - Running setup.py install for moztest 09:05:45 INFO - Running setup.py install for mozversion 09:05:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:05:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:05:45 INFO - Cleaning up... 09:05:45 INFO - Return code: 0 09:05:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:45 INFO - Using env: {'DISPLAY': ':0', 09:05:45 INFO - 'HOME': '/home/cltbld', 09:05:45 INFO - 'LANG': 'en_US.UTF-8', 09:05:45 INFO - 'LANGUAGE': 'en_US:en', 09:05:45 INFO - 'LOGNAME': 'cltbld', 09:05:45 INFO - 'MAIL': '/var/mail/cltbld', 09:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:45 INFO - 'MOZ_NO_REMOTE': '1', 09:05:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:45 INFO - 'NO_EM_RESTART': '1', 09:05:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:45 INFO - 'PWD': '/builds/slave/test', 09:05:45 INFO - 'SHELL': '/bin/bash', 09:05:45 INFO - 'SHLVL': '1', 09:05:45 INFO - 'TERM': 'linux', 09:05:45 INFO - 'TMOUT': '86400', 09:05:45 INFO - 'USER': 'cltbld', 09:05:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:45 INFO - '_': '/tools/buildbot/bin/python'} 09:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:45 INFO - Running setup.py (path:/tmp/pip-0ZCqPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:05:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:45 INFO - Running setup.py (path:/tmp/pip-stoZOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:46 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:46 INFO - Running setup.py (path:/tmp/pip-RtMgQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:46 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:46 INFO - Running setup.py (path:/tmp/pip-YG4apa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:46 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:46 INFO - Running setup.py (path:/tmp/pip-MRkMex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:46 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:46 INFO - Running setup.py (path:/tmp/pip-3AIFOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:46 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:46 INFO - Running setup.py (path:/tmp/pip-YAEAAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:46 INFO - Running setup.py (path:/tmp/pip-IG1rC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:46 INFO - Running setup.py (path:/tmp/pip-zhlRPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:46 INFO - Running setup.py (path:/tmp/pip-040V1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:46 INFO - Running setup.py (path:/tmp/pip-lZWryx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:46 INFO - Running setup.py (path:/tmp/pip-zjlUNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:46 INFO - Running setup.py (path:/tmp/pip-5cMtc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:46 INFO - Running setup.py (path:/tmp/pip-vYd15_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:46 INFO - Running setup.py (path:/tmp/pip-021Yyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:05:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:46 INFO - Running setup.py (path:/tmp/pip-Kqxi2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:47 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)) 09:05:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:47 INFO - Running setup.py (path:/tmp/pip-2NBn1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:47 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)) 09:05:47 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)) 09:05:47 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)) 09:05:47 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)) 09:05:47 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)) 09:05:47 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)) 09:05:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:05:47 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 09:05:47 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 09:05:47 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 09:05:47 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 09:05:47 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 09:05:47 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 09:05:48 INFO - Downloading blessings-1.6.tar.gz 09:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:05:49 INFO - Installing collected packages: blessings 09:05:49 INFO - Running setup.py install for blessings 09:05:49 INFO - Successfully installed blessings 09:05:49 INFO - Cleaning up... 09:05:49 INFO - Return code: 0 09:05:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:49 INFO - Reading from file tmpfile_stdout 09:05:49 INFO - Current package versions: 09:05:49 INFO - argparse == 1.2.1 09:05:49 INFO - blessings == 1.6 09:05:49 INFO - blobuploader == 1.2.4 09:05:49 INFO - docopt == 0.6.1 09:05:49 INFO - functools32 == 3.2.3-2 09:05:49 INFO - jsonschema == 2.5.1 09:05:49 INFO - manifestparser == 1.1 09:05:49 INFO - mozInstall == 1.12 09:05:49 INFO - mozcrash == 0.17 09:05:49 INFO - mozdebug == 0.1 09:05:49 INFO - mozdevice == 0.48 09:05:49 INFO - mozfile == 1.2 09:05:49 INFO - mozhttpd == 0.7 09:05:49 INFO - mozinfo == 0.9 09:05:49 INFO - mozleak == 0.1 09:05:49 INFO - mozlog == 3.4 09:05:49 INFO - moznetwork == 0.27 09:05:49 INFO - mozprocess == 0.23 09:05:49 INFO - mozprofile == 0.28 09:05:49 INFO - mozrunner == 6.12 09:05:49 INFO - mozscreenshot == 0.1 09:05:49 INFO - mozsystemmonitor == 0.3 09:05:49 INFO - moztest == 0.7 09:05:49 INFO - mozversion == 1.4 09:05:49 INFO - psutil == 3.1.1 09:05:49 INFO - requests == 1.2.3 09:05:49 INFO - wsgiref == 0.1.2 09:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:49 INFO - Using env: {'DISPLAY': ':0', 09:05:49 INFO - 'HOME': '/home/cltbld', 09:05:49 INFO - 'LANG': 'en_US.UTF-8', 09:05:49 INFO - 'LANGUAGE': 'en_US:en', 09:05:49 INFO - 'LOGNAME': 'cltbld', 09:05:49 INFO - 'MAIL': '/var/mail/cltbld', 09:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:49 INFO - 'MOZ_NO_REMOTE': '1', 09:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:49 INFO - 'NO_EM_RESTART': '1', 09:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:49 INFO - 'PWD': '/builds/slave/test', 09:05:49 INFO - 'SHELL': '/bin/bash', 09:05:49 INFO - 'SHLVL': '1', 09:05:49 INFO - 'TERM': 'linux', 09:05:49 INFO - 'TMOUT': '86400', 09:05:49 INFO - 'USER': 'cltbld', 09:05:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:49 INFO - '_': '/tools/buildbot/bin/python'} 09:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:05:49 INFO - Cleaning up... 09:05:49 INFO - Return code: 0 09:05:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2a3edc0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x157d7b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, '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': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', '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 09:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:05:49 INFO - Using env: {'DISPLAY': ':0', 09:05:49 INFO - 'HOME': '/home/cltbld', 09:05:49 INFO - 'LANG': 'en_US.UTF-8', 09:05:49 INFO - 'LANGUAGE': 'en_US:en', 09:05:49 INFO - 'LOGNAME': 'cltbld', 09:05:49 INFO - 'MAIL': '/var/mail/cltbld', 09:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:49 INFO - 'MOZ_NO_REMOTE': '1', 09:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:49 INFO - 'NO_EM_RESTART': '1', 09:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:49 INFO - 'PWD': '/builds/slave/test', 09:05:49 INFO - 'SHELL': '/bin/bash', 09:05:49 INFO - 'SHLVL': '1', 09:05:49 INFO - 'TERM': 'linux', 09:05:49 INFO - 'TMOUT': '86400', 09:05:49 INFO - 'USER': 'cltbld', 09:05:49 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:49 INFO - '_': '/tools/buildbot/bin/python'} 09:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:05:49 INFO - Cleaning up... 09:05:49 INFO - Return code: 0 09:05:49 INFO - Running post-action listener: _resource_record_post_action 09:05:49 INFO - Running post-action listener: _start_resource_monitoring 09:05:49 INFO - Starting resource monitoring. 09:05:49 INFO - [mozharness: 2016-09-30 16:05:49.932361Z] Finished create-virtualenv step (success) 09:05:49 INFO - [mozharness: 2016-09-30 16:05:49.932865Z] Running install step. 09:05:49 INFO - Running pre-action listener: _resource_record_pre_action 09:05:49 INFO - Running main action method: install 09:05:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:50 INFO - Reading from file tmpfile_stdout 09:05:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:05:50 INFO - Reading from file tmpfile_stdout 09:05:50 INFO - Output received: 09:05:50 INFO - Usage: mozinstall [options] installer 09:05:50 INFO - Options: 09:05:50 INFO - -h, --help show this help message and exit 09:05:50 INFO - -d DEST, --destination=DEST 09:05:50 INFO - Directory to install application into. [default: 09:05:50 INFO - "/builds/slave/test"] 09:05:50 INFO - --app=APP Application being installed. [default: firefox] 09:05:50 INFO - mkdir: /builds/slave/test/build/application 09:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:06:07 INFO - Reading from file tmpfile_stdout 09:06:07 INFO - Output received: 09:06:07 INFO - /builds/slave/test/build/application/firefox/firefox 09:06:07 INFO - Running post-action listener: _resource_record_post_action 09:06:07 INFO - [mozharness: 2016-09-30 16:06:07.230360Z] Finished install step (success) 09:06:07 INFO - [mozharness: 2016-09-30 16:06:07.230604Z] Running run-tests step. 09:06:07 INFO - Running pre-action listener: _resource_record_pre_action 09:06:07 INFO - Running main action method: run_tests 09:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:07 INFO - Python 2.7.3 09:06:07 INFO - Return code: 0 09:06:07 INFO - grabbing minidump binary from tooltool 09:06:07 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:07 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 0x160c790>, '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 0x160d690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x160db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:07 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 09:06:07 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 09:06:07 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 09:06:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:07 INFO - Return code: 0 09:06:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:06:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:06:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:06:07 INFO - Using env: {'DISPLAY': ':0', 09:06:07 INFO - 'HOME': '/home/cltbld', 09:06:07 INFO - 'LANG': 'en_US.UTF-8', 09:06:07 INFO - 'LANGUAGE': 'en_US:en', 09:06:07 INFO - 'LOGNAME': 'cltbld', 09:06:07 INFO - 'MAIL': '/var/mail/cltbld', 09:06:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:07 INFO - 'MOZ_NO_REMOTE': '1', 09:06:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:07 INFO - 'NO_EM_RESTART': '1', 09:06:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:07 INFO - 'PWD': '/builds/slave/test', 09:06:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:07 INFO - 'SHELL': '/bin/bash', 09:06:07 INFO - 'SHLVL': '1', 09:06:07 INFO - 'TERM': 'linux', 09:06:07 INFO - 'TMOUT': '86400', 09:06:07 INFO - 'USER': 'cltbld', 09:06:07 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673', 09:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:07 INFO - '_': '/tools/buildbot/bin/python'} 09:06:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:06:07 INFO - mozversion application_buildid: 20160930063003 09:06:07 INFO - mozversion application_changeset: 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 09:06:07 INFO - mozversion application_display_name: Nightly 09:06:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:07 INFO - mozversion application_name: Firefox 09:06:07 INFO - mozversion application_remotingname: firefox 09:06:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:06:07 INFO - mozversion application_vendor: Mozilla 09:06:07 INFO - mozversion application_version: 52.0a1 09:06:07 INFO - mozversion platform_buildid: 20160930063003 09:06:07 INFO - mozversion platform_changeset: 659bd1ae3b672329d9fc1e9b655bc02c7ecb8090 09:06:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:06:07 INFO - mozversion platform_version: 52.0a1 09:06:07 INFO - using testdate: 1475251567 09:06:07 INFO - actual date: 1475251567 09:06:07 INFO - starting webserver on 'localhost:49567' 09:06:07 INFO - SUITE-START | Running 2 tests 09:06:07 INFO - TEST-START | dromaeo_css 09:06:07 INFO - operating with platform_type : linux_ 09:06:07 INFO - Initialising browser for dromaeo_css test... 09:06:07 INFO - TEST-INFO | started process 10376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAl9yFL/profile http://localhost:49567/getInfo.html) 09:06:12 INFO - PROCESS | 10376 | __metrics Screen width/height:1600/1200 09:06:12 INFO - PROCESS | 10376 | colorDepth:24 09:06:12 INFO - PROCESS | 10376 | Browser inner width/height: 1024/697 09:06:12 INFO - PROCESS | 10376 | __metrics 09:06:12 INFO - PROCESS | 10376 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:12 INFO - PROCESS | 10376 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:13 INFO - PROCESS | 10376 | console.error: PushService: 09:06:13 INFO - PROCESS | 10376 | stateChangeProcessEnqueue: Error transitioning state 09:06:13 INFO - PROCESS | 10376 | UnknownError 09:06:13 INFO - TEST-INFO | 10376: exit 0 09:06:13 INFO - Browser initialized. 09:06:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:13 INFO - TEST-INFO | started process 10456 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAl9yFL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:26 INFO - PROCESS | 10456 | RSS: Main: 173907968 09:06:26 INFO - PROCESS | 10456 | 09:07:17 INFO - PROCESS | 10456 | 1475251637283 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] 09:09:26 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49567/tests/dromaeo/cssquery-ext.html) 09:09:27 INFO - PROCESS | 10456 | RSS: Main: 326758400 09:09:27 INFO - PROCESS | 10456 | 09:12:30 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-ext.html (next: http://localhost:49567/tests/dromaeo/cssquery-jquery.html) 09:12:30 INFO - PROCESS | 10456 | RSS: Main: 271704064 09:12:30 INFO - PROCESS | 10456 | 09:15:32 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49567/tests/dromaeo/cssquery-mootools.html) 09:15:33 INFO - PROCESS | 10456 | RSS: Main: 214323200 09:15:33 INFO - PROCESS | 10456 | 09:18:35 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49567/tests/dromaeo/cssquery-prototype.html) 09:18:36 INFO - PROCESS | 10456 | RSS: Main: 253386752 09:18:36 INFO - PROCESS | 10456 | 09:21:38 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49567/tests/dromaeo/cssquery-yui.html) 09:21:39 INFO - PROCESS | 10456 | RSS: Main: 221298688 09:21:39 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | Cycle 1(1): loaded http://localhost:49567/tests/dromaeo/cssquery-yui.html (next: http://localhost:49567/tests/dromaeo/cssquery-dojo.html) 09:24:42 INFO - PROCESS | 10456 | RSS: Main: 309010432 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | __start_tp_report 09:24:42 INFO - PROCESS | 10456 | _x_x_mozilla_page_load 09:24:42 INFO - PROCESS | 10456 | _x_x_mozilla_page_load_details 09:24:42 INFO - PROCESS | 10456 | |i|pagename|runs| 09:24:42 INFO - PROCESS | 10456 | |0;dojo.html;2937.1922721251653;2979.0615955973853;2979.374331390408;2997.91006269812;3024.3497648005687;4993.775280112394;5061.316722242496;5119.872003199917;5224.63427560071;5251.186066159747;5144.151215049522;5145.176771716526;5201.505856943594;5238.554722848562;5315.096433606286;4690.038542098879;4741.834035808747;4770.952290477086;4773.0931123086675;4779.426468823729;4861.8784530386665;4896.069746748107;4903.607711383081;4927.359443272369;4945.826896058638;4627.490975992671;4653.697509661861;4654.348391225231;4811.446683631375;4817.132916075105;4708.293755936603;4746.786394612251;4769.3799806025345;4779.737114458703;4799.184138696395;4724.881877953045;4757.857264282077;4764.880877978044;4776.09254241693;4813.735244561548;4974.651774375795;4980.203167493184;5178.0679477694;5179.948200517984;5222.060029194743;5342;5393.919091213635;5400.243965844785;5440.238366628675;5489.807856724996;5440.619156659035;5454.59090568209;5468.535174510172;5470.890582188334;5477.7261136943;4919.434265059493;5117.130087885069;5124.333836601255;5132.5124113209185;5203.687778733288;5335.466453354634;5525.171224316385;5706.087026075891;5713.542916566665;5713.885722285531;5409.7295135243085;5417.864553386118;5419.160030195326;5430.619856609999;5432.782688692408;5442.2380866678695;5446.863828404243;5478.369987451494;5505.311836020497;5579.637323573955;5299.12564426873;5329.25390445338;5386.326709161355;5425.864353391197;5468;5498.367687715884;5500.119980803132;5501.064818980783;5504.394516603171;5561.054620714488;4296.9570304297185;4306.2248795217165;4312.353147027971;4358.65130789536;4366.039471316305;3748.231612519427;3754.9624503754612;3830;3830.2531006453473;3847.9230415391535;5285.048691235615;5291.153415453509;5295.602829787781;5326.893462130735;5338.332708411449;5554.111342185231;5590.860228494239;5598;5600.187972744011;5603.551715862721;5066.088104141216;5119.948800512022;5153.768080436389;5277.445868183862;5346.091164501966;4873.951260487421;4893.804247830047;4899.681520701214;4957.479464656231;4970.701757894465;5783.94216057834;5787.363390027093;5792.681402522901;5793.884122317614;5801.535877129735;5336.706481143575;5374.381946076095;5576.414476479911;5649.039663257338;5710.571707122065;5660.745266463102;5741.856453588526;5756.568257380797;5768.3078030634915;5843.912341314969;5430.51125398716;5468.781248750005;5473.480019398151;5473.562115030868;5473.589480789036;5216.217567364925;5565.8608534785335;5576.944230557642;5581.218629391808;5636.915446268391;5221.347331583552;5313.760880760452;5389.919151212656;5434.701091440009;5523.1991361253195;5391.379991301051;5405.729713514387;5411.567074634098;5411.567074634098;5412.783488660567;5328.520433160879;5371.462853714597;5389.946100538945;5391.7304134793885;5456.01791677486;5219.947800521946;5258.6581872178185;5347.545458635904;5408.4321146280645;5410.242566040679;5282.445343238905;5328.626996110235;5333.839984800462;5334.06653835585;5389.730513474232 09:24:42 INFO - PROCESS | 10456 | |1;ext.html;572.4161355417474;603.664965943901;607.6080927801565;612.2989177391884;619.6065498408509;30485.390292194144;42451.15097698048;43074.56925430745;43190;43195.568044319545;44060.33909491352;44499.55500444995;44517.55482445175;44623.55376446235;44655.77672111635;9048.230900373475;9195.724128276162;9199.632014719436;9213.262938964901;9215.400998935082;8946.373753837233;9045.954770226139;9053.230475409599;9065.093490650916;9076.8638470423;9040.095990400972;9065.093490650916;9076.183143517082;9077.273818094569;9083.091690830897;9033.09669033098;9067.591958361823;9078.409903356262;9083.818323633557;9088.136627020422;9067.637294508211;9120.270378369782;9133.90866091344;9138.680146194918;9144.177024067865;41985;42373;42383.36424953628;42424.36363454551;42451;3393.6606339365867;3513.1568423578415;3562.946555801665;3628.665563456927;4019.437278780973;31565.84217078923;32118.518222226685;32255.6774432255;32376;33580.83209583937;4383.101464199832;4393.275109606911;4397.802098905557;4437.4009508716235;4608.331791890158;318046;318719;320133.9999999977;321295;321860;55202;55482.16776748352;55567.16649250264;55576.44423555794;55584;53756.731216343665;54330.45669543333;55615;56552.717236413555;56555.23393873956;15519.767203491956;15610.14144222056;15648.374065037271;15654.45209417665;15655.452059177875;11110.722231944266;13037.608871733863;13192.340382981005;13204.405801738945;13206.867931320756;13607.115537490032;14649.707005860037;15395.46115885961;15430.459933902259;15841.128737919526;5797.072468405035;5930.644161350333;5974.522038236931;6013.744845682869;6035.913535563553;996.6611352140162;1004.1991063823539;1064.2231171245035;1073.765170054428;1105.4141305108305;30282.697173027987;30889.227769305948;31022;31298.843505782326;31710.207244819063;1067.6103222323952;1090.9509072091776;1114.5987444519967;1118.1725523112839;1161.361251311775;15736.60658483547;16354.672906541802;16469.670606588043;16586.6682666346;16694.332226710794;479942;480168;482078;483267;483411;55529.722351388795;55805.44194558002;55836.88326233452;55860;55871.44128558744;1326.7014921642553;1363.9318034098453;1375.9724805504034;1386.8266466691664;1389.4511667891127;28783.856080719463;30231.732620054023;30399.240018999702;30718.385632287675;30821.234843650145;48360.7581962102;48590;48714;48756.26865596948;48764.26853597128;426256;428517;428821;428839;429074;31756.523652145697;32451.50593819639;33033;39202.80398597989;39584.20831583295;37690.811545942095;42689.359659605754;43884;45209.77395113003;45721.31418028799;37898.43152352662;38223;38303.616963830005;38362.6163738359;38365.232695346494;37570;38284.617153828105;38347.2330553393;39686;40180.1963960725;39046.51324342358;39731.60268397279;39953.80023099982;40169.1966160681;40225.798871005456;32451.350972979937;33600.30396656317;33962.66037339595;33974.3205135891;35034.29931401409;26026.60960085638;26567.601485978113;26583.202503924913;26597.93608255648;26868.865655671598 09:24:42 INFO - PROCESS | 10456 | |2;jquery.html;371.5615573623124;403.89902524368887;403.9919201615967;407.98368065277384;414.2770864840853;12961.48154073837;15057;18689.719654205197;19229.51926201842;19374.418767437;16920.74618880715;17439.825601743978;17492.562685932826;17720.113994300296;17727.64544709105;5536.529395001429;5773.509251713588;5773.653580785145;5773.942260577403;5777.59556831022;5397.109476936321;5640.576956728241;5649.858753531174;5657.179708942201;5664.575156863231;5624.437556244384;5635.63368381056;5639.210510528529;5640.04119299718;5641.069223578104;5016.849494515171;5076.111680455924;5104.719240441774;5108.106081435771;5112.309838171855;4601.907961840744;4619.099275641252;4624.838130665445;4625.95374046262;4629.976850115728;21245.468863278387;21838.23566175176;21869.234576789895;22010.779892201033;22025.66961495579;2268.625676763332;2338.777816107467;2353.7999270062064;3031.1361262040214;3047.923801904948;13446.731065378637;13962.93018534911;14109.506167284198;14170.433182672681;14613;1887.1036257777582;1887.282832523646;1887.9528011799678;1899.810018998103;1951.7853036166011;51862.740686296325;62449.37550624526;71092.99999999948;78922;79316;10407;10718.571257149782;10756.623518176983;10985.946464626362;10994.230403871812;14991.850081499264;15002.174880381466;15024.549263522113;15063.924680376747;15129.092254464547;8005.07941586725;8141.4708043978135;8223.671053157927;8305.46014509055;8458.915410845813;7881.881771773428;7885.960570197112;7979.202079791974;8114.797130071795;8127.837443251102;6314.62112273265;6444.086605811957;6553.246376666649;6586.341365863375;6650.534462587572;1888.9338873139375;1920.3758778397078;1949.4931317857458;1966.1938605172097;1968.6062787442568;924.7318277699526;994;1015.28422462164;1023.1303392116641;1023.8976102389702;18682.25270989145;18922.3377181798;19025.619487610446;19186.904065479583;19251.807481925003;972.4489439344235;1030.7423144213947;1039.3815679670583;1099.2580008494235;1127.21095233337;9878.950605246928;10789.892101079045;10982.450877456095;11028.889711102947;11165.776684466266;150253;151002;151510;151927;155055;78016;86301;87952;88279.55860220658;98276;483.92015317472925;489.76736050375786;511.9027384796877;514.9227615857651;518.1088527732219;10853.40306283162;10960.342379457257;11187.776244475228;11436;11476.311421314747;40961.56634517777;41609.37585936274;42214.57785422229;42606.786966064974;43338.349924750524;58260.4173958255;59127.40872591219;59568.702156488944;60304;60341.396586035335;8579.485230886166;8774;8784.692535761218;8795.208431241219;9121.680741174026;9865.704028879145;12323.075769317158;12705.301208433624;12979.221246725227;13040.869591303965;10439.269251152346;10581.259311848096;11221.887781122085;11281;11436.885631143583;10776.191785616267;11103.777924441627;11180.888191117985;11305.208635395444;11458;11009.2843965142;11311.717207069889;11472.31166130035;11518.769624607294;11705;8649.567521623767;8702.651893924172;8802.119788021148;8944.311344573121;8974.282057435259;10208.438535880301;10958.616448424267;11151.665450036511;11293;11691.064714822964 09:24:42 INFO - PROCESS | 10456 | |3;mootools.html;1440.3806363263795;1476.1512130524948;1623.537291871816;1778.7509748635184;1782.438531862464;7347.375473084794;7618.352440042603;8211.137830527798;8791.692290769824;8798;8563;8798.51608161551;8871.95564022179;9071.818563628723;9074.183323500883;2341.4380548668323;2375.524895020994;2378.8334816562847;2382.95234095318;2385.642153676945;1957.3247229705728;2022.1203776357258;2328.3364241191266;2343.6718859359703;2347.3544775186874;2019.2629690163058;2062.206050670495;2064.3394113883564;2263.875486848223;2348.8473249238746;2250.5386395788823;2251.268337790225;2251.324602619224;2256.131389415079;2259.4690247791755;2190.463336482556;2211.8341124415574;2283.3264187064688;2287.2452090809993;2291.2897001929455;9057.818843623156;9073.546322683906;9076.500792456412;9099.135582119688;9103.453792772389;5229.555487783543;5376.1398176292;5390.056740070472;5391.838244852661;5395.7032363220005;3335.449650807614;3394.9830250848836;3490.685838274542;3493.4410494320787;3511.8244087795506;1121.7676823121928;1150.4937827355936;1173.865005524367;1202.0984261803646;1206.336514916801;11506.30962142274;11886.702832429171;12399.380030998414;12670.55653052148;12781.488740450372;4485.654303708874;4503.909921801545;4599.425071866017;4620.953790462052;4621;9809.362391444532;9869.703908882744;9872.358296710834;9884.851727224097;9889.060539248903;5314.840554783363;5329.360476742816;5336.546393556514;5336.67979921206;5341.252224688546;4688.718676879398;4850.320955066293;4996.4503904570465;4997.775100120431;5001.699898006132;3672.155404257036;3701.1487357907295;3768.660820526166;3785.1104990327253;3785.432185172191;1137.624583887315;1163.116031815826;1163.87197408285;1172.3962159487871;1187.4656404617783;974.4932635029745;984.9310548261553;985.935914165577;1006.8942761009988;1011.8330475471465;5731.570132240098;5972.880542389128;6000.21997140369;6094.542909281733;6101.7559297627595;461.46239630829706;467.75910406140866;469.5679974423538;960.3229723045237;1024.7540590258434;4143.461350024478;4211.873643790691;4263.253930562139;4315.395844581761;4324.221640104812;11075.833862492069;11486.138539609563;11495.310281382977;11514.30914145154;11521.711957201138;11335.659930202108;11364.261323013978;11371.488283027118;11389.601363952388;11389.715257118638;7936.523808571505;8256.463329883538;8311.418200726011;8374.916250837536;8377.748667539983;5802.274715660543;5926.881462370642;5946.256717910262;5970.089671106171;6063.484603808549;9512.524373781422;9640.614375424904;9821.803563928825;9832.360896541702;9906.801863962824;9908.058734420218;10009.69970900874;10015.849762253718;10079.69760907174;10127.139193168663;7646.885296720442;7783.883241751265;7852.60736963161;8042.03495580534;8053.31546818527;8677.088905664814;8739.082396348293;8745.518996455256;8753.781155471164;8761.693340733043;7415;7587.620618968919;7657.234276572521;7818.765437036898;7943.920560794318;7595.3164215220895;7699.88450173259;7704.614769261627;7750.728724494616;7792.688292468238;6947.861042779015;7030.9296907030275;7045.612491313027;7054.435645148274;7059.682314295766;5088.720120393414;5111.182210846247;5131.794728210979;5148.30497882781;5337.466253374631;5290.418054013979;5375.00562395958;5393.865153371197;5474;5537.141743029676 09:24:42 INFO - PROCESS | 10456 | |4;prototype.html;314.15335670368364;329.08842506257685;330.79490715756225;340.39409850466166;342.7583553594716;4995.775190116445;5210.036143313485;5234.214867769836;5273.288106105674;5293.206019097137;5058.747062646862;5181.222816577515;5205.412349233482;5208.073012985191;5218.843434696965;4168.395582640531;4169.291220492509;4182.832686692544;4185.769782661952;4187.623113919746;3953.861614843481;4007.939880901789;4062.7156099073077;4104.8768536943935;4170.061736109366;4066.8983275418204;4099.221147981889;4101.8974525636795;4102.466679331683;4109.116539943911;4098.770368889336;4137.275976704064;4146.730462519927;4165.75005499671;4177.185448837447;3446.776828945341;3631.7457777955287;3709.4250391189407;3735.7571757835926;3745.4756334113245;5050.671706339076;5074.467180945984;5108.386993560759;5115.488451154892;5154.613403994678;4593.977030114828;4627.884302892421;4651.325557794101;4673.789679464432;4682.836100736492;5135.974320128375;5336.466353364672;5338.252644629755;5424.647397919162;5474.39781624021;3915.766533541954;3916.902077448058;3919.3734600140906;3921.274564205631;3922.8234729437245;5569.718964638156;5603.2435621191435;5635.436456354373;5639.633423827479;5652.293463317085;3802.144517483544;3956.9604303957167;3990.620891015349;3990.980045099814;4030.395440683862;4053.6757059435176;4071.7353372030725;4079.224947260011;4084.8350374301667;4099.692523060782;4394.648428125743;4494.842680506166;4499.955000449953;4653.9761252524395;4669.556392142809;5458.317710286215;5470.507654311131;5477.233187353694;5477.75350109246;5484.149956756709;1408.4295860176644;1419.5528408551274;1420.2614640387148;1426.3510102903153;1434.9354279057468;901.2879824938357;917.6191880369572;921.6359537982594;929.5398777604994;942.222666300305;4657.883552911204;4662.230731929261;4694.859154225379;4732.645051621143;4748.928766068511;4940.6788558743565;4949.282354058713;4960.255961605716;4976.2038073908;5027.215338554834;4498.055408364215;4672.415948006499;4719.764011799396;4731;4752.738599377068;2627.6452678888486;2640.168346970698;2641.2868525498393;2655.654764880553;2657.774089202401;5061.898762024739;5089.694618322913;5104.642675012714;5115.488451154854;5122.334096567422;4783.56947874686;5033.245013248042;5077.882865769525;5109.624024828473;5110.770015349318;5122.821750997217;5129.384473863161;5137.691738495628;5147.176451767774;5150.330457040635;4784.904301914012;4890.266460031024;4898.82854100119;4908.312836203006;4959.950400495949;4621.145088158702;4693.610179248564;4835.606374844334;4907.975460122677;4910.803567857389;5007.123753343223;5013.24801279811;5038.62412183845;5055.638000729474;5086.491350864884;3710.2950439416422;3728.738988270795;3729.310077635646;3734.8319325630955;3747.1756213632957;4762.404699412574;4921.917178220786;4925.408950925912;4926.433460152129;4993.450720420678;4865.513448655106;4865.683730557503;4868.780904859219;4889.731064791471;4910.8281210157475;4790.874144575952;4801.68789028712;4812.25410061441;4842.225243960949;4932.630052745986;4600.976995115137;4763.642726795435;4790.329353890388;4815.494373090915;4829.6860704054125;4776.808927642855;4841.733704646278;4891.095147397743;4904.852854414374;4904.92642610354;4657.765692091666;4670.883227919193;4677.812887484462;4728;4734.597559207506 09:24:42 INFO - PROCESS | 10456 | |5;yui.html;746.9589172595507;747.7943565519485;790.213737331355;832.2818476459088;844.0420123160213;16197.433089841858;17058.402955896545;17116.572085697833;17119.657606847857;17212.741808872877;13579.592612221624;13782.448702051932;13788.034837561374;13811.309434528284;13886;642.9485641148708;674.0058413839572;769.0387016229713;794.7814351053448;801.2548330053048;798.7284323330067;808.3573559020592;810.720301495983;810.7973006748313;811.0186674124287;860.4149178558578;860.7460799064291;866.3242670716819;866.5017614871474;867.3451544084207;677.695037233247;706.9293070692889;817.8977627796526;861.6940985949991;861.7759382560548;620.8385819686869;629.7229219143608;629.8173529676388;629.820501157168;629.8582818865765;15126.394944202219;15150.621234469116;15176.848231517764;15214.695706085817;15222.46721364758;14167.220802855838;14474.348654310581;14682.412703491847;14698.926505367512;14722.926385368004;13148.276844773534;13197.20816750998;13229.603111906563;13491.190528568317;13567.59297221085;4344.45694288214;4380.255356589356;4385.912281754379;4389.341598760179;4414.801333939949;76903;82508.58745706292;83042;83049;83153;11170.944145279222;11260.741695003317;11385.316880987002;11491.942540287413;11621.47703353351;21979.670304945437;21998.560028799333;22120.557588848133;22144.66782998256;22145.778542214695;7630.2751238632245;7717.729879454192;7767.495112817649;7924.167962363984;7954.363650907913;4732.952670473252;4770.260609605517;4859.927101093486;4884.438289596671;4900.411950565955;3497.160681436437;3614.4397618369194;3615.258871931248;3618.4753210784816;3619.2037751694584;600.2526854066715;608.5648761135809;610.8533951851524;621.7015832400473;630.5806638585381;542.3573065916822;622.5051084387923;636.6021236727046;660.7125900233414;662.493192707579;8935.374523783403;9327.860082098774;9403.952980235055;9527.758074667561;9576.712698619052;644.9161608990895;651.1539784710399;666.2504682232488;681.536555142498;708.2917082917083;4381.868543943687;4494.977525112354;4651.348811166439;4676.625869930397;4763.04739052191;78785.60607196928;122962;126412;126887;129781;106733;109257;110248.4487577557;110355;110694;58088.19118088157;78837;91975.67391509697;94649.5267523658;96303.51848240713;8205.466644668077;8756.124387561193;8891.555422228734;8920.107989201028;9095.818083638422;38400.80799595984;49183.75408122937;51996.220056698425;52364.21453678274;53155;71747;72910.63544682243;79660.60169699346;79843.60078199572;82668;11258.718532036764;11523.481443334902;11582.594609188638;11606.419679015846;11629.18595698293;12480.126391152533;12637.873621264038;12839.229646221256;12902.548410805577;12912.870871291543;13496.5276215332;13773.311334433038;14270;14392.20842853653;15340.92329538345;13660.86339136596;14155.221462819643;14229.50196743109;14688;14804.185769782334;13224.669383265495;13908.582742517741;14953.327100280296;15078.095314281178;15085.698286034438;10997.12023038171;11840.289582624726;12066.939665301617;12261.570845020382;12589.559365422167;10463.68608941733;11792;11953.760924781629;12051.216670916363;12445.75108497843 09:24:42 INFO - PROCESS | 10456 | __end_tp_report 09:24:42 INFO - PROCESS | 10456 | __start_cc_report 09:24:42 INFO - PROCESS | 10456 | _x_x_mozilla_cycle_collect,1205 09:24:42 INFO - PROCESS | 10456 | __end_cc_report 09:24:42 INFO - PROCESS | 10456 | __startTimestamp1475252682487__endTimestamp 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | ------- Summary: start ------- 09:24:42 INFO - PROCESS | 10456 | Number of tests: 6 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#0] dojo.html Cycles:165 Average:5143.55 Median:5329.25 stddev:564.99 (10.6%) stddev-sans-first:539.56 09:24:42 INFO - PROCESS | 10456 | Values: 2937.2 2979.1 2979.4 2997.9 3024.3 4993.8 5061.3 5119.9 5224.6 5251.2 5144.2 5145.2 5201.5 5238.6 5315.1 4690.0 4741.8 4771.0 4773.1 4779.4 4861.9 4896.1 4903.6 4927.4 4945.8 4627.5 4653.7 4654.3 4811.4 4817.1 4708.3 4746.8 4769.4 4779.7 4799.2 4724.9 4757.9 4764.9 4776.1 4813.7 4974.7 4980.2 5178.1 5179.9 5222.1 5342.0 5393.9 5400.2 5440.2 5489.8 5440.6 5454.6 5468.5 5470.9 5477.7 4919.4 5117.1 5124.3 5132.5 5203.7 5335.5 5525.2 5706.1 5713.5 5713.9 5409.7 5417.9 5419.2 5430.6 5432.8 5442.2 5446.9 5478.4 5505.3 5579.6 5299.1 5329.3 5386.3 5425.9 5468.0 5498.4 5500.1 5501.1 5504.4 5561.1 4297.0 4306.2 4312.4 4358.7 4366.0 3748.2 3755.0 3830.0 3830.3 3847.9 5285.0 5291.2 5295.6 5326.9 5338.3 5554.1 5590.9 5598.0 5600.2 5603.6 5066.1 5119.9 5153.8 5277.4 5346.1 4874.0 4893.8 4899.7 4957.5 4970.7 5783.9 5787.4 5792.7 5793.9 5801.5 5336.7 5374.4 5576.4 5649.0 5710.6 5660.7 5741.9 5756.6 5768.3 5843.9 5430.5 5468.8 5473.5 5473.6 5473.6 5216.2 5565.9 5576.9 5581.2 5636.9 5221.3 5313.8 5389.9 5434.7 5523.2 5391.4 5405.7 5411.6 5411.6 5412.8 5328.5 5371.5 5389.9 5391.7 5456.0 5219.9 5258.7 5347.5 5408.4 5410.2 5282.4 5328.6 5333.8 5334.1 5389.7 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#1] ext.html Cycles:180 Average:56806.16 Median:41105.40 stddev:109935.26 (267.4%) stddev-sans-first:110162.58 09:24:42 INFO - PROCESS | 10456 | Values: 572.4 603.7 607.6 612.3 619.6 30485.4 42451.2 43074.6 43190.0 43195.6 44060.3 44499.6 44517.6 44623.6 44655.8 9048.2 9195.7 9199.6 9213.3 9215.4 8946.4 9046.0 9053.2 9065.1 9076.9 9040.1 9065.1 9076.2 9077.3 9083.1 9033.1 9067.6 9078.4 9083.8 9088.1 9067.6 9120.3 9133.9 9138.7 9144.2 41985.0 42373.0 42383.4 42424.4 42451.0 3393.7 3513.2 3562.9 3628.7 4019.4 31565.8 32118.5 32255.7 32376.0 33580.8 4383.1 4393.3 4397.8 4437.4 4608.3 318046.0 318719.0 320134.0 321295.0 321860.0 55202.0 55482.2 55567.2 55576.4 55584.0 53756.7 54330.5 55615.0 56552.7 56555.2 15519.8 15610.1 15648.4 15654.5 15655.5 11110.7 13037.6 13192.3 13204.4 13206.9 13607.1 14649.7 15395.5 15430.5 15841.1 5797.1 5930.6 5974.5 6013.7 6035.9 996.7 1004.2 1064.2 1073.8 1105.4 30282.7 30889.2 31022.0 31298.8 31710.2 1067.6 1091.0 1114.6 1118.2 1161.4 15736.6 16354.7 16469.7 16586.7 16694.3 479942.0 480168.0 482078.0 483267.0 483411.0 55529.7 55805.4 55836.9 55860.0 55871.4 1326.7 1363.9 1376.0 1386.8 1389.5 28783.9 30231.7 30399.2 30718.4 30821.2 48360.8 48590.0 48714.0 48756.3 48764.3 426256.0 428517.0 428821.0 428839.0 429074.0 31756.5 32451.5 33033.0 39202.8 39584.2 37690.8 42689.4 43884.0 45209.8 45721.3 37898.4 38223.0 38303.6 38362.6 38365.2 37570.0 38284.6 38347.2 39686.0 40180.2 39046.5 39731.6 39953.8 40169.2 40225.8 32451.4 33600.3 33962.7 33974.3 35034.3 26026.6 26567.6 26583.2 26597.9 26868.9 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#2] jquery.html Cycles:180 Average:18902.99 Median:2303.70 stddev:29655.22 (1287.3%) stddev-sans-first:29705.77 09:24:42 INFO - PROCESS | 10456 | Values: 371.6 403.9 404.0 408.0 414.3 12961.5 15057.0 18689.7 19229.5 19374.4 16920.7 17439.8 17492.6 17720.1 17727.6 5536.5 5773.5 5773.7 5773.9 5777.6 5397.1 5640.6 5649.9 5657.2 5664.6 5624.4 5635.6 5639.2 5640.0 5641.1 5016.8 5076.1 5104.7 5108.1 5112.3 4601.9 4619.1 4624.8 4626.0 4630.0 21245.5 21838.2 21869.2 22010.8 22025.7 2268.6 2338.8 2353.8 3031.1 3047.9 13446.7 13962.9 14109.5 14170.4 14613.0 1887.1 1887.3 1888.0 1899.8 1951.8 51862.7 62449.4 71093.0 78922.0 79316.0 10407.0 10718.6 10756.6 10985.9 10994.2 14991.9 15002.2 15024.5 15063.9 15129.1 8005.1 8141.5 8223.7 8305.5 8458.9 7881.9 7886.0 7979.2 8114.8 8127.8 6314.6 6444.1 6553.2 6586.3 6650.5 1888.9 1920.4 1949.5 1966.2 1968.6 924.7 994.0 1015.3 1023.1 1023.9 18682.3 18922.3 19025.6 19186.9 19251.8 972.4 1030.7 1039.4 1099.3 1127.2 9879.0 10789.9 10982.5 11028.9 11165.8 150253.0 151002.0 151510.0 151927.0 155055.0 78016.0 86301.0 87952.0 88279.6 98276.0 483.9 489.8 511.9 514.9 518.1 10853.4 10960.3 11187.8 11436.0 11476.3 40961.6 41609.4 42214.6 42606.8 43338.3 58260.4 59127.4 59568.7 60304.0 60341.4 8579.5 8774.0 8784.7 8795.2 9121.7 9865.7 12323.1 12705.3 12979.2 13040.9 10439.3 10581.3 11221.9 11281.0 11436.9 10776.2 11103.8 11180.9 11305.2 11458.0 11009.3 11311.7 11472.3 11518.8 11705.0 8649.6 8702.7 8802.1 8944.3 8974.3 10208.4 10958.6 11151.7 11293.0 11691.1 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#3] mootools.html Cycles:180 Average:5806.27 Median:5099.95 stddev:3329.88 (65.3%) stddev-sans-first:3323.06 09:24:42 INFO - PROCESS | 10456 | Values: 1440.4 1476.2 1623.5 1778.8 1782.4 7347.4 7618.4 8211.1 8791.7 8798.0 8563.0 8798.5 8872.0 9071.8 9074.2 2341.4 2375.5 2378.8 2383.0 2385.6 1957.3 2022.1 2328.3 2343.7 2347.4 2019.3 2062.2 2064.3 2263.9 2348.8 2250.5 2251.3 2251.3 2256.1 2259.5 2190.5 2211.8 2283.3 2287.2 2291.3 9057.8 9073.5 9076.5 9099.1 9103.5 5229.6 5376.1 5390.1 5391.8 5395.7 3335.4 3395.0 3490.7 3493.4 3511.8 1121.8 1150.5 1173.9 1202.1 1206.3 11506.3 11886.7 12399.4 12670.6 12781.5 4485.7 4503.9 4599.4 4621.0 4621.0 9809.4 9869.7 9872.4 9884.9 9889.1 5314.8 5329.4 5336.5 5336.7 5341.3 4688.7 4850.3 4996.5 4997.8 5001.7 3672.2 3701.1 3768.7 3785.1 3785.4 1137.6 1163.1 1163.9 1172.4 1187.5 974.5 984.9 985.9 1006.9 1011.8 5731.6 5972.9 6000.2 6094.5 6101.8 461.5 467.8 469.6 960.3 1024.8 4143.5 4211.9 4263.3 4315.4 4324.2 11075.8 11486.1 11495.3 11514.3 11521.7 11335.7 11364.3 11371.5 11389.6 11389.7 7936.5 8256.5 8311.4 8374.9 8377.7 5802.3 5926.9 5946.3 5970.1 6063.5 9512.5 9640.6 9821.8 9832.4 9906.8 9908.1 10009.7 10015.8 10079.7 10127.1 7646.9 7783.9 7852.6 8042.0 8053.3 8677.1 8739.1 8745.5 8753.8 8761.7 7415.0 7587.6 7657.2 7818.8 7943.9 7595.3 7699.9 7704.6 7750.7 7792.7 6947.9 7030.9 7045.6 7054.4 7059.7 5088.7 5111.2 5131.8 5148.3 5337.5 5290.4 5375.0 5393.9 5474.0 5537.1 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#4] prototype.html Cycles:180 Average:4319.51 Median:4780.19 stddev:1199.65 (25.1%) stddev-sans-first:1164.74 09:24:42 INFO - PROCESS | 10456 | Values: 314.2 329.1 330.8 340.4 342.8 4995.8 5210.0 5234.2 5273.3 5293.2 5058.7 5181.2 5205.4 5208.1 5218.8 4168.4 4169.3 4182.8 4185.8 4187.6 3953.9 4007.9 4062.7 4104.9 4170.1 4066.9 4099.2 4101.9 4102.5 4109.1 4098.8 4137.3 4146.7 4165.8 4177.2 3446.8 3631.7 3709.4 3735.8 3745.5 5050.7 5074.5 5108.4 5115.5 5154.6 4594.0 4627.9 4651.3 4673.8 4682.8 5136.0 5336.5 5338.3 5424.6 5474.4 3915.8 3916.9 3919.4 3921.3 3922.8 5569.7 5603.2 5635.4 5639.6 5652.3 3802.1 3957.0 3990.6 3991.0 4030.4 4053.7 4071.7 4079.2 4084.8 4099.7 4394.6 4494.8 4500.0 4654.0 4669.6 5458.3 5470.5 5477.2 5477.8 5484.1 1408.4 1419.6 1420.3 1426.4 1434.9 901.3 917.6 921.6 929.5 942.2 4657.9 4662.2 4694.9 4732.6 4748.9 4940.7 4949.3 4960.3 4976.2 5027.2 4498.1 4672.4 4719.8 4731.0 4752.7 2627.6 2640.2 2641.3 2655.7 2657.8 5061.9 5089.7 5104.6 5115.5 5122.3 4783.6 5033.2 5077.9 5109.6 5110.8 5122.8 5129.4 5137.7 5147.2 5150.3 4784.9 4890.3 4898.8 4908.3 4960.0 4621.1 4693.6 4835.6 4908.0 4910.8 5007.1 5013.2 5038.6 5055.6 5086.5 3710.3 3728.7 3729.3 3734.8 3747.2 4762.4 4921.9 4925.4 4926.4 4993.5 4865.5 4865.7 4868.8 4889.7 4910.8 4790.9 4801.7 4812.3 4842.2 4932.6 4601.0 4763.6 4790.3 4815.5 4829.7 4776.8 4841.7 4891.1 4904.9 4904.9 4657.8 4670.9 4677.8 4728.0 4734.6 09:24:42 INFO - PROCESS | 10456 | 09:24:42 INFO - PROCESS | 10456 | [#5] yui.html Cycles:180 Average:21293.57 Median:4454.89 stddev:31403.00 (704.9%) stddev-sans-first:31453.19 09:24:42 INFO - PROCESS | 10456 | Values: 747.0 747.8 790.2 832.3 844.0 16197.4 17058.4 17116.6 17119.7 17212.7 13579.6 13782.4 13788.0 13811.3 13886.0 642.9 674.0 769.0 794.8 801.3 798.7 808.4 810.7 810.8 811.0 860.4 860.7 866.3 866.5 867.3 677.7 706.9 817.9 861.7 861.8 620.8 629.7 629.8 629.8 629.9 15126.4 15150.6 15176.8 15214.7 15222.5 14167.2 14474.3 14682.4 14698.9 14722.9 13148.3 13197.2 13229.6 13491.2 13567.6 4344.5 4380.3 4385.9 4389.3 4414.8 76903.0 82508.6 83042.0 83049.0 83153.0 11170.9 11260.7 11385.3 11491.9 11621.5 21979.7 21998.6 22120.6 22144.7 22145.8 7630.3 7717.7 7767.5 7924.2 7954.4 4733.0 4770.3 4859.9 4884.4 4900.4 3497.2 3614.4 3615.3 3618.5 3619.2 600.3 608.6 610.9 621.7 630.6 542.4 622.5 636.6 660.7 662.5 8935.4 9327.9 9404.0 9527.8 9576.7 644.9 651.2 666.3 681.5 708.3 4381.9 4495.0 4651.3 4676.6 4763.0 78785.6 122962.0 126412.0 126887.0 129781.0 106733.0 109257.0 110248.4 110355.0 110694.0 58088.2 78837.0 91975.7 94649.5 96303.5 8205.5 8756.1 8891.6 8920.1 9095.8 38400.8 49183.8 51996.2 52364.2 53155.0 71747.0 72910.6 79660.6 79843.6 82668.0 11258.7 11523.5 11582.6 11606.4 11629.2 12480.1 12637.9 12839.2 12902.5 12912.9 13496.5 13773.3 14270.0 14392.2 15340.9 13660.9 14155.2 14229.5 14688.0 14804.2 13224.7 13908.6 14953.3 15078.1 15085.7 10997.1 11840.3 12066.9 12261.6 12589.6 10463.7 11792.0 11953.8 12051.2 12445.8 09:24:42 INFO - PROCESS | 10456 | -------- Summary: end -------- 09:24:42 INFO - PROCESS | 10456 | 09:24:43 INFO - TEST-INFO | 10456: exit 0 09:24:43 INFO - TEST-OK | dromaeo_css | took 1115859ms 09:24:43 INFO - TEST-START | kraken 09:24:43 INFO - operating with platform_type : linux_ 09:24:43 INFO - Initialising browser for kraken test... 09:24:43 INFO - TEST-INFO | started process 10767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMfTFgy/profile http://localhost:49567/getInfo.html) 09:24:47 INFO - PROCESS | 10767 | __metrics Screen width/height:1600/1200 09:24:47 INFO - PROCESS | 10767 | colorDepth:24 09:24:47 INFO - PROCESS | 10767 | Browser inner width/height: 1024/697 09:24:47 INFO - PROCESS | 10767 | __metrics 09:24:48 INFO - PROCESS | 10767 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:48 INFO - PROCESS | 10767 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:48 INFO - PROCESS | 10767 | console.error: PushService: 09:24:48 INFO - PROCESS | 10767 | stateChangeProcessEnqueue: Error transitioning state 09:24:48 INFO - PROCESS | 10767 | UnknownError 09:24:49 INFO - TEST-INFO | 10767: exit 0 09:24:49 INFO - Browser initialized. 09:24:49 INFO - Running cycle 1/1 for kraken test... 09:24:49 INFO - TEST-INFO | started process 10847 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMfTFgy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:01 INFO - PROCESS | 10847 | RSS: Main: 174882816 09:25:01 INFO - PROCESS | 10847 | 09:25:04 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49567/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:05 INFO - PROCESS | 10847 | RSS: Main: 233492480 09:25:05 INFO - PROCESS | 10847 | 09:25:07 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49567/tests/kraken/driver.html?testName=audio-dft) 09:25:08 INFO - PROCESS | 10847 | RSS: Main: 217686016 09:25:08 INFO - PROCESS | 10847 | 09:25:11 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49567/tests/kraken/driver.html?testName=audio-fft) 09:25:12 INFO - PROCESS | 10847 | RSS: Main: 299438080 09:25:12 INFO - PROCESS | 10847 | 09:25:14 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49567/tests/kraken/driver.html?testName=audio-oscillator) 09:25:14 INFO - PROCESS | 10847 | RSS: Main: 209043456 09:25:14 INFO - PROCESS | 10847 | 09:25:16 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49567/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:17 INFO - PROCESS | 10847 | RSS: Main: 214237184 09:25:17 INFO - PROCESS | 10847 | 09:25:20 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49567/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:21 INFO - PROCESS | 10847 | RSS: Main: 250408960 09:25:21 INFO - PROCESS | 10847 | 09:25:24 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49567/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:25 INFO - PROCESS | 10847 | RSS: Main: 249401344 09:25:25 INFO - PROCESS | 10847 | 09:25:27 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49567/tests/kraken/driver.html?testName=json-parse-financial) 09:25:28 INFO - PROCESS | 10847 | RSS: Main: 249511936 09:25:28 INFO - PROCESS | 10847 | 09:25:30 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49567/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:30 INFO - PROCESS | 10847 | RSS: Main: 211374080 09:25:30 INFO - PROCESS | 10847 | 09:25:32 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:33 INFO - PROCESS | 10847 | RSS: Main: 218370048 09:25:33 INFO - PROCESS | 10847 | 09:25:35 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:35 INFO - PROCESS | 10847 | RSS: Main: 223543296 09:25:35 INFO - PROCESS | 10847 | 09:25:38 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:38 INFO - PROCESS | 10847 | RSS: Main: 224407552 09:25:38 INFO - PROCESS | 10847 | 09:25:41 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:42 INFO - PROCESS | 10847 | RSS: Main: 222355456 09:25:42 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | Cycle 1(1): loaded http://localhost:49567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49567/tests/kraken/driver.html?testName=ai-astar) 09:25:44 INFO - PROCESS | 10847 | RSS: Main: 273911808 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | __start_tp_report 09:25:44 INFO - PROCESS | 10847 | _x_x_mozilla_page_load 09:25:44 INFO - PROCESS | 10847 | _x_x_mozilla_page_load_details 09:25:44 INFO - PROCESS | 10847 | |i|pagename|runs| 09:25:44 INFO - PROCESS | 10847 | |0;ai-astar;108;111;111;109;110;111;111;111;112;112 09:25:44 INFO - PROCESS | 10847 | |1;audio-beat-detection;148;123;122;169;122;125;122;123;154;122 09:25:44 INFO - PROCESS | 10847 | |2;audio-dft;160;159;161;160;161;160;162;160;161;162 09:25:44 INFO - PROCESS | 10847 | |3;audio-fft;74;76;76;111;76;76;78;75;99;75 09:25:44 INFO - PROCESS | 10847 | |4;audio-oscillator;83;83;115;81;83;81;99;81;81;118 09:25:44 INFO - PROCESS | 10847 | |5;imaging-gaussian-blur;116;117;117;117;116;117;116;116;116;116 09:25:44 INFO - PROCESS | 10847 | |6;imaging-darkroom;146;146;145;146;146;145;145;145;145;146 09:25:44 INFO - PROCESS | 10847 | |7;imaging-desaturate;95;94;95;95;95;95;95;94;95;95 09:25:44 INFO - PROCESS | 10847 | |8;json-parse-financial;54;54;53;52;54;55;54;54;55;54 09:25:44 INFO - PROCESS | 10847 | |9;json-stringify-tinderbox;61;61;61;61;62;62;62;61;62;61 09:25:44 INFO - PROCESS | 10847 | |10;stanford-crypto-aes;80;79;79;87;85;79;88;78;82;79 09:25:44 INFO - PROCESS | 10847 | |11;stanford-crypto-ccm;129;129;128;136;133;141;131;144;174;132 09:25:44 INFO - PROCESS | 10847 | |12;stanford-crypto-pbkdf2;167;167;166;171;166;165;171;170;166;165 09:25:44 INFO - PROCESS | 10847 | |13;stanford-crypto-sha256-iterative;68;65;66;69;75;66;73;66;65;66 09:25:44 INFO - PROCESS | 10847 | __end_tp_report 09:25:44 INFO - PROCESS | 10847 | __start_cc_report 09:25:44 INFO - PROCESS | 10847 | _x_x_mozilla_cycle_collect,1638 09:25:44 INFO - PROCESS | 10847 | __end_cc_report 09:25:44 INFO - PROCESS | 10847 | __startTimestamp1475252744366__endTimestamp 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | ------- Summary: start ------- 09:25:44 INFO - PROCESS | 10847 | Number of tests: 14 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.26 (1.1%) stddev-sans-first:0.93 09:25:44 INFO - PROCESS | 10847 | Values: 108.0 111.0 111.0 109.0 110.0 111.0 111.0 111.0 112.0 112.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:124.00 stddev:17.35 (14.0%) stddev-sans-first:17.54 09:25:44 INFO - PROCESS | 10847 | Values: 148.0 123.0 122.0 169.0 122.0 125.0 122.0 123.0 154.0 122.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 09:25:44 INFO - PROCESS | 10847 | Values: 160.0 159.0 161.0 160.0 161.0 160.0 162.0 160.0 161.0 162.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#3] audio-fft Cycles:10 Average:81.60 Median:76.00 stddev:12.69 (16.7%) stddev-sans-first:13.16 09:25:44 INFO - PROCESS | 10847 | Values: 74.0 76.0 76.0 111.0 76.0 76.0 78.0 75.0 99.0 75.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:82.00 stddev:14.75 (18.0%) stddev-sans-first:15.39 09:25:44 INFO - PROCESS | 10847 | Values: 83.0 83.0 115.0 81.0 83.0 81.0 99.0 81.0 81.0 118.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:25:44 INFO - PROCESS | 10847 | Values: 116.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 116.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 09:25:44 INFO - PROCESS | 10847 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 145.0 146.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:25:44 INFO - PROCESS | 10847 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 09:25:44 INFO - PROCESS | 10847 | Values: 54.0 54.0 53.0 52.0 54.0 55.0 54.0 54.0 55.0 54.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:25:44 INFO - PROCESS | 10847 | Values: 61.0 61.0 61.0 61.0 62.0 62.0 62.0 61.0 62.0 61.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:3.72 (4.6%) stddev-sans-first:3.90 09:25:44 INFO - PROCESS | 10847 | Values: 80.0 79.0 79.0 87.0 85.0 79.0 88.0 78.0 82.0 79.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:134.50 stddev:13.81 (10.3%) stddev-sans-first:14.28 09:25:44 INFO - PROCESS | 10847 | Values: 129.0 129.0 128.0 136.0 133.0 141.0 131.0 144.0 174.0 132.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.00 stddev:2.37 (1.4%) stddev-sans-first:2.51 09:25:44 INFO - PROCESS | 10847 | Values: 167.0 167.0 166.0 171.0 166.0 165.0 171.0 170.0 166.0 165.0 09:25:44 INFO - PROCESS | 10847 | 09:25:44 INFO - PROCESS | 10847 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:67.00 stddev:3.48 (5.2%) stddev-sans-first:3.69 09:25:44 INFO - PROCESS | 10847 | Values: 68.0 65.0 66.0 69.0 75.0 66.0 73.0 66.0 65.0 66.0 09:25:44 INFO - PROCESS | 10847 | -------- Summary: end -------- 09:25:44 INFO - PROCESS | 10847 | 09:25:45 INFO - TEST-INFO | 10847: exit 0 09:25:45 INFO - TEST-OK | kraken | took 62119ms 09:25:45 INFO - SUITE-END | took 1177s 09:25:45 INFO - Completed test suite (00:19:38) 09:25:45 INFO - javascript benchmark 09:25:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2937.1922721251653, 2979.0615955973853, 2979.374331390408, 2997.91006269812, 3024.3497648005687, 4993.775280112394, 5061.316722242496, 5119.872003199917, 5224.63427560071, 5251.186066159747, 5144.151215049522, 5145.176771716526, 5201.505856943594, 5238.554722848562, 5315.096433606286, 4690.038542098879, 4741.834035808747, 4770.952290477086, 4773.0931123086675, 4779.426468823729, 4861.8784530386665, 4896.069746748107, 4903.607711383081, 4927.359443272369, 4945.826896058638, 4627.490975992671, 4653.697509661861, 4654.348391225231, 4811.446683631375, 4817.132916075105, 4708.293755936603, 4746.786394612251, 4769.3799806025345, 4779.737114458703, 4799.184138696395, 4724.881877953045, 4757.857264282077, 4764.880877978044, 4776.09254241693, 4813.735244561548, 4974.651774375795, 4980.203167493184, 5178.0679477694, 5179.948200517984, 5222.060029194743, 5342.0, 5393.919091213635, 5400.243965844785, 5440.238366628675, 5489.807856724996, 5440.619156659035, 5454.59090568209, 5468.535174510172, 5470.890582188334, 5477.7261136943, 4919.434265059493, 5117.130087885069, 5124.333836601255, 5132.5124113209185, 5203.687778733288, 5335.466453354634, 5525.171224316385, 5706.087026075891, 5713.542916566665, 5713.885722285531, 5409.7295135243085, 5417.864553386118, 5419.160030195326, 5430.619856609999, 5432.782688692408, 5442.2380866678695, 5446.863828404243, 5478.369987451494, 5505.311836020497, 5579.637323573955, 5299.12564426873, 5329.25390445338, 5386.326709161355, 5425.864353391197, 5468.0, 5498.367687715884, 5500.119980803132, 5501.064818980783, 5504.394516603171, 5561.054620714488, 4296.9570304297185, 4306.2248795217165, 4312.353147027971, 4358.65130789536, 4366.039471316305, 3748.231612519427, 3754.9624503754612, 3830.0, 3830.2531006453473, 3847.9230415391535, 5285.048691235615, 5291.153415453509, 5295.602829787781, 5326.893462130735, 5338.332708411449, 5554.111342185231, 5590.860228494239, 5598.0, 5600.187972744011, 5603.551715862721, 5066.088104141216, 5119.948800512022, 5153.768080436389, 5277.445868183862, 5346.091164501966, 4873.951260487421, 4893.804247830047, 4899.681520701214, 4957.479464656231, 4970.701757894465, 5783.94216057834, 5787.363390027093, 5792.681402522901, 5793.884122317614, 5801.535877129735, 5336.706481143575, 5374.381946076095, 5576.414476479911, 5649.039663257338, 5710.571707122065, 5660.745266463102, 5741.856453588526, 5756.568257380797, 5768.3078030634915, 5843.912341314969, 5430.51125398716, 5468.781248750005, 5473.480019398151, 5473.562115030868, 5473.589480789036, 5216.217567364925, 5565.8608534785335, 5576.944230557642, 5581.218629391808, 5636.915446268391, 5221.347331583552, 5313.760880760452, 5389.919151212656, 5434.701091440009, 5523.1991361253195, 5391.379991301051, 5405.729713514387, 5411.567074634098, 5411.567074634098, 5412.783488660567, 5328.520433160879, 5371.462853714597, 5389.946100538945, 5391.7304134793885, 5456.01791677486, 5219.947800521946, 5258.6581872178185, 5347.545458635904, 5408.4321146280645, 5410.242566040679, 5282.445343238905, 5328.626996110235, 5333.839984800462, 5334.06653835585, 5389.730513474232], "value": 5106.519375301348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.4161355417474, 603.664965943901, 607.6080927801565, 612.2989177391884, 619.6065498408509, 30485.390292194144, 42451.15097698048, 43074.56925430745, 43190.0, 43195.568044319545, 44060.33909491352, 44499.55500444995, 44517.55482445175, 44623.55376446235, 44655.77672111635, 9048.230900373475, 9195.724128276162, 9199.632014719436, 9213.262938964901, 9215.400998935082, 8946.373753837233, 9045.954770226139, 9053.230475409599, 9065.093490650916, 9076.8638470423, 9040.095990400972, 9065.093490650916, 9076.183143517082, 9077.273818094569, 9083.091690830897, 9033.09669033098, 9067.591958361823, 9078.409903356262, 9083.818323633557, 9088.136627020422, 9067.637294508211, 9120.270378369782, 9133.90866091344, 9138.680146194918, 9144.177024067865, 41985.0, 42373.0, 42383.36424953628, 42424.36363454551, 42451.0, 3393.6606339365867, 3513.1568423578415, 3562.946555801665, 3628.665563456927, 4019.437278780973, 31565.84217078923, 32118.518222226685, 32255.6774432255, 32376.0, 33580.83209583937, 4383.101464199832, 4393.275109606911, 4397.802098905557, 4437.4009508716235, 4608.331791890158, 318046.0, 318719.0, 320133.9999999977, 321295.0, 321860.0, 55202.0, 55482.16776748352, 55567.16649250264, 55576.44423555794, 55584.0, 53756.731216343665, 54330.45669543333, 55615.0, 56552.717236413555, 56555.23393873956, 15519.767203491956, 15610.14144222056, 15648.374065037271, 15654.45209417665, 15655.452059177875, 11110.722231944266, 13037.608871733863, 13192.340382981005, 13204.405801738945, 13206.867931320756, 13607.115537490032, 14649.707005860037, 15395.46115885961, 15430.459933902259, 15841.128737919526, 5797.072468405035, 5930.644161350333, 5974.522038236931, 6013.744845682869, 6035.913535563553, 996.6611352140162, 1004.1991063823539, 1064.2231171245035, 1073.765170054428, 1105.4141305108305, 30282.697173027987, 30889.227769305948, 31022.0, 31298.843505782326, 31710.207244819063, 1067.6103222323952, 1090.9509072091776, 1114.5987444519967, 1118.1725523112839, 1161.361251311775, 15736.60658483547, 16354.672906541802, 16469.670606588043, 16586.6682666346, 16694.332226710794, 479942.0, 480168.0, 482078.0, 483267.0, 483411.0, 55529.722351388795, 55805.44194558002, 55836.88326233452, 55860.0, 55871.44128558744, 1326.7014921642553, 1363.9318034098453, 1375.9724805504034, 1386.8266466691664, 1389.4511667891127, 28783.856080719463, 30231.732620054023, 30399.240018999702, 30718.385632287675, 30821.234843650145, 48360.7581962102, 48590.0, 48714.0, 48756.26865596948, 48764.26853597128, 426256.0, 428517.0, 428821.0, 428839.0, 429074.0, 31756.523652145697, 32451.50593819639, 33033.0, 39202.80398597989, 39584.20831583295, 37690.811545942095, 42689.359659605754, 43884.0, 45209.77395113003, 45721.31418028799, 37898.43152352662, 38223.0, 38303.616963830005, 38362.6163738359, 38365.232695346494, 37570.0, 38284.617153828105, 38347.2330553393, 39686.0, 40180.1963960725, 39046.51324342358, 39731.60268397279, 39953.80023099982, 40169.1966160681, 40225.798871005456, 32451.350972979937, 33600.30396656317, 33962.66037339595, 33974.3205135891, 35034.29931401409, 26026.60960085638, 26567.601485978113, 26583.202503924913, 26597.93608255648, 26868.865655671598], "value": 19521.069144080415, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.5615573623124, 403.89902524368887, 403.9919201615967, 407.98368065277384, 414.2770864840853, 12961.48154073837, 15057.0, 18689.719654205197, 19229.51926201842, 19374.418767437, 16920.74618880715, 17439.825601743978, 17492.562685932826, 17720.113994300296, 17727.64544709105, 5536.529395001429, 5773.509251713588, 5773.653580785145, 5773.942260577403, 5777.59556831022, 5397.109476936321, 5640.576956728241, 5649.858753531174, 5657.179708942201, 5664.575156863231, 5624.437556244384, 5635.63368381056, 5639.210510528529, 5640.04119299718, 5641.069223578104, 5016.849494515171, 5076.111680455924, 5104.719240441774, 5108.106081435771, 5112.309838171855, 4601.907961840744, 4619.099275641252, 4624.838130665445, 4625.95374046262, 4629.976850115728, 21245.468863278387, 21838.23566175176, 21869.234576789895, 22010.779892201033, 22025.66961495579, 2268.625676763332, 2338.777816107467, 2353.7999270062064, 3031.1361262040214, 3047.923801904948, 13446.731065378637, 13962.93018534911, 14109.506167284198, 14170.433182672681, 14613.0, 1887.1036257777582, 1887.282832523646, 1887.9528011799678, 1899.810018998103, 1951.7853036166011, 51862.740686296325, 62449.37550624526, 71092.99999999948, 78922.0, 79316.0, 10407.0, 10718.571257149782, 10756.623518176983, 10985.946464626362, 10994.230403871812, 14991.850081499264, 15002.174880381466, 15024.549263522113, 15063.924680376747, 15129.092254464547, 8005.07941586725, 8141.4708043978135, 8223.671053157927, 8305.46014509055, 8458.915410845813, 7881.881771773428, 7885.960570197112, 7979.202079791974, 8114.797130071795, 8127.837443251102, 6314.62112273265, 6444.086605811957, 6553.246376666649, 6586.341365863375, 6650.534462587572, 1888.9338873139375, 1920.3758778397078, 1949.4931317857458, 1966.1938605172097, 1968.6062787442568, 924.7318277699526, 994.0, 1015.28422462164, 1023.1303392116641, 1023.8976102389702, 18682.25270989145, 18922.3377181798, 19025.619487610446, 19186.904065479583, 19251.807481925003, 972.4489439344235, 1030.7423144213947, 1039.3815679670583, 1099.2580008494235, 1127.21095233337, 9878.950605246928, 10789.892101079045, 10982.450877456095, 11028.889711102947, 11165.776684466266, 150253.0, 151002.0, 151510.0, 151927.0, 155055.0, 78016.0, 86301.0, 87952.0, 88279.55860220658, 98276.0, 483.92015317472925, 489.76736050375786, 511.9027384796877, 514.9227615857651, 518.1088527732219, 10853.40306283162, 10960.342379457257, 11187.776244475228, 11436.0, 11476.311421314747, 40961.56634517777, 41609.37585936274, 42214.57785422229, 42606.786966064974, 43338.349924750524, 58260.4173958255, 59127.40872591219, 59568.702156488944, 60304.0, 60341.396586035335, 8579.485230886166, 8774.0, 8784.692535761218, 8795.208431241219, 9121.680741174026, 9865.704028879145, 12323.075769317158, 12705.301208433624, 12979.221246725227, 13040.869591303965, 10439.269251152346, 10581.259311848096, 11221.887781122085, 11281.0, 11436.885631143583, 10776.191785616267, 11103.777924441627, 11180.888191117985, 11305.208635395444, 11458.0, 11009.2843965142, 11311.717207069889, 11472.31166130035, 11518.769624607294, 11705.0, 8649.567521623767, 8702.651893924172, 8802.119788021148, 8944.311344573121, 8974.282057435259, 10208.438535880301, 10958.616448424267, 11151.665450036511, 11293.0, 11691.064714822964], "value": 8592.79085478176, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.3806363263795, 1476.1512130524948, 1623.537291871816, 1778.7509748635184, 1782.438531862464, 7347.375473084794, 7618.352440042603, 8211.137830527798, 8791.692290769824, 8798.0, 8563.0, 8798.51608161551, 8871.95564022179, 9071.818563628723, 9074.183323500883, 2341.4380548668323, 2375.524895020994, 2378.8334816562847, 2382.95234095318, 2385.642153676945, 1957.3247229705728, 2022.1203776357258, 2328.3364241191266, 2343.6718859359703, 2347.3544775186874, 2019.2629690163058, 2062.206050670495, 2064.3394113883564, 2263.875486848223, 2348.8473249238746, 2250.5386395788823, 2251.268337790225, 2251.324602619224, 2256.131389415079, 2259.4690247791755, 2190.463336482556, 2211.8341124415574, 2283.3264187064688, 2287.2452090809993, 2291.2897001929455, 9057.818843623156, 9073.546322683906, 9076.500792456412, 9099.135582119688, 9103.453792772389, 5229.555487783543, 5376.1398176292, 5390.056740070472, 5391.838244852661, 5395.7032363220005, 3335.449650807614, 3394.9830250848836, 3490.685838274542, 3493.4410494320787, 3511.8244087795506, 1121.7676823121928, 1150.4937827355936, 1173.865005524367, 1202.0984261803646, 1206.336514916801, 11506.30962142274, 11886.702832429171, 12399.380030998414, 12670.55653052148, 12781.488740450372, 4485.654303708874, 4503.909921801545, 4599.425071866017, 4620.953790462052, 4621.0, 9809.362391444532, 9869.703908882744, 9872.358296710834, 9884.851727224097, 9889.060539248903, 5314.840554783363, 5329.360476742816, 5336.546393556514, 5336.67979921206, 5341.252224688546, 4688.718676879398, 4850.320955066293, 4996.4503904570465, 4997.775100120431, 5001.699898006132, 3672.155404257036, 3701.1487357907295, 3768.660820526166, 3785.1104990327253, 3785.432185172191, 1137.624583887315, 1163.116031815826, 1163.87197408285, 1172.3962159487871, 1187.4656404617783, 974.4932635029745, 984.9310548261553, 985.935914165577, 1006.8942761009988, 1011.8330475471465, 5731.570132240098, 5972.880542389128, 6000.21997140369, 6094.542909281733, 6101.7559297627595, 461.46239630829706, 467.75910406140866, 469.5679974423538, 960.3229723045237, 1024.7540590258434, 4143.461350024478, 4211.873643790691, 4263.253930562139, 4315.395844581761, 4324.221640104812, 11075.833862492069, 11486.138539609563, 11495.310281382977, 11514.30914145154, 11521.711957201138, 11335.659930202108, 11364.261323013978, 11371.488283027118, 11389.601363952388, 11389.715257118638, 7936.523808571505, 8256.463329883538, 8311.418200726011, 8374.916250837536, 8377.748667539983, 5802.274715660543, 5926.881462370642, 5946.256717910262, 5970.089671106171, 6063.484603808549, 9512.524373781422, 9640.614375424904, 9821.803563928825, 9832.360896541702, 9906.801863962824, 9908.058734420218, 10009.69970900874, 10015.849762253718, 10079.69760907174, 10127.139193168663, 7646.885296720442, 7783.883241751265, 7852.60736963161, 8042.03495580534, 8053.31546818527, 8677.088905664814, 8739.082396348293, 8745.518996455256, 8753.781155471164, 8761.693340733043, 7415.0, 7587.620618968919, 7657.234276572521, 7818.765437036898, 7943.920560794318, 7595.3164215220895, 7699.88450173259, 7704.614769261627, 7750.728724494616, 7792.688292468238, 6947.861042779015, 7030.9296907030275, 7045.612491313027, 7054.435645148274, 7059.682314295766, 5088.720120393414, 5111.182210846247, 5131.794728210979, 5148.30497882781, 5337.466253374631, 5290.418054013979, 5375.00562395958, 5393.865153371197, 5474.0, 5537.141743029676], "value": 4596.698916065782, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.15335670368364, 329.08842506257685, 330.79490715756225, 340.39409850466166, 342.7583553594716, 4995.775190116445, 5210.036143313485, 5234.214867769836, 5273.288106105674, 5293.206019097137, 5058.747062646862, 5181.222816577515, 5205.412349233482, 5208.073012985191, 5218.843434696965, 4168.395582640531, 4169.291220492509, 4182.832686692544, 4185.769782661952, 4187.623113919746, 3953.861614843481, 4007.939880901789, 4062.7156099073077, 4104.8768536943935, 4170.061736109366, 4066.8983275418204, 4099.221147981889, 4101.8974525636795, 4102.466679331683, 4109.116539943911, 4098.770368889336, 4137.275976704064, 4146.730462519927, 4165.75005499671, 4177.185448837447, 3446.776828945341, 3631.7457777955287, 3709.4250391189407, 3735.7571757835926, 3745.4756334113245, 5050.671706339076, 5074.467180945984, 5108.386993560759, 5115.488451154892, 5154.613403994678, 4593.977030114828, 4627.884302892421, 4651.325557794101, 4673.789679464432, 4682.836100736492, 5135.974320128375, 5336.466353364672, 5338.252644629755, 5424.647397919162, 5474.39781624021, 3915.766533541954, 3916.902077448058, 3919.3734600140906, 3921.274564205631, 3922.8234729437245, 5569.718964638156, 5603.2435621191435, 5635.436456354373, 5639.633423827479, 5652.293463317085, 3802.144517483544, 3956.9604303957167, 3990.620891015349, 3990.980045099814, 4030.395440683862, 4053.6757059435176, 4071.7353372030725, 4079.224947260011, 4084.8350374301667, 4099.692523060782, 4394.648428125743, 4494.842680506166, 4499.955000449953, 4653.9761252524395, 4669.556392142809, 5458.317710286215, 5470.507654311131, 5477.233187353694, 5477.75350109246, 5484.149956756709, 1408.4295860176644, 1419.5528408551274, 1420.2614640387148, 1426.3510102903153, 1434.9354279057468, 901.2879824938357, 917.6191880369572, 921.6359537982594, 929.5398777604994, 942.222666300305, 4657.883552911204, 4662.230731929261, 4694.859154225379, 4732.645051621143, 4748.928766068511, 4940.6788558743565, 4949.282354058713, 4960.255961605716, 4976.2038073908, 5027.215338554834, 4498.055408364215, 4672.415948006499, 4719.764011799396, 4731.0, 4752.738599377068, 2627.6452678888486, 2640.168346970698, 2641.2868525498393, 2655.654764880553, 2657.774089202401, 5061.898762024739, 5089.694618322913, 5104.642675012714, 5115.488451154854, 5122.334096567422, 4783.56947874686, 5033.245013248042, 5077.882865769525, 5109.624024828473, 5110.770015349318, 5122.821750997217, 5129.384473863161, 5137.691738495628, 5147.176451767774, 5150.330457040635, 4784.904301914012, 4890.266460031024, 4898.82854100119, 4908.312836203006, 4959.950400495949, 4621.145088158702, 4693.610179248564, 4835.606374844334, 4907.975460122677, 4910.803567857389, 5007.123753343223, 5013.24801279811, 5038.62412183845, 5055.638000729474, 5086.491350864884, 3710.2950439416422, 3728.738988270795, 3729.310077635646, 3734.8319325630955, 3747.1756213632957, 4762.404699412574, 4921.917178220786, 4925.408950925912, 4926.433460152129, 4993.450720420678, 4865.513448655106, 4865.683730557503, 4868.780904859219, 4889.731064791471, 4910.8281210157475, 4790.874144575952, 4801.68789028712, 4812.25410061441, 4842.225243960949, 4932.630052745986, 4600.976995115137, 4763.642726795435, 4790.329353890388, 4815.494373090915, 4829.6860704054125, 4776.808927642855, 4841.733704646278, 4891.095147397743, 4904.852854414374, 4904.92642610354, 4657.765692091666, 4670.883227919193, 4677.812887484462, 4728.0, 4734.597559207506], "value": 3946.495601208267, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.9589172595507, 747.7943565519485, 790.213737331355, 832.2818476459088, 844.0420123160213, 16197.433089841858, 17058.402955896545, 17116.572085697833, 17119.657606847857, 17212.741808872877, 13579.592612221624, 13782.448702051932, 13788.034837561374, 13811.309434528284, 13886.0, 642.9485641148708, 674.0058413839572, 769.0387016229713, 794.7814351053448, 801.2548330053048, 798.7284323330067, 808.3573559020592, 810.720301495983, 810.7973006748313, 811.0186674124287, 860.4149178558578, 860.7460799064291, 866.3242670716819, 866.5017614871474, 867.3451544084207, 677.695037233247, 706.9293070692889, 817.8977627796526, 861.6940985949991, 861.7759382560548, 620.8385819686869, 629.7229219143608, 629.8173529676388, 629.820501157168, 629.8582818865765, 15126.394944202219, 15150.621234469116, 15176.848231517764, 15214.695706085817, 15222.46721364758, 14167.220802855838, 14474.348654310581, 14682.412703491847, 14698.926505367512, 14722.926385368004, 13148.276844773534, 13197.20816750998, 13229.603111906563, 13491.190528568317, 13567.59297221085, 4344.45694288214, 4380.255356589356, 4385.912281754379, 4389.341598760179, 4414.801333939949, 76903.0, 82508.58745706292, 83042.0, 83049.0, 83153.0, 11170.944145279222, 11260.741695003317, 11385.316880987002, 11491.942540287413, 11621.47703353351, 21979.670304945437, 21998.560028799333, 22120.557588848133, 22144.66782998256, 22145.778542214695, 7630.2751238632245, 7717.729879454192, 7767.495112817649, 7924.167962363984, 7954.363650907913, 4732.952670473252, 4770.260609605517, 4859.927101093486, 4884.438289596671, 4900.411950565955, 3497.160681436437, 3614.4397618369194, 3615.258871931248, 3618.4753210784816, 3619.2037751694584, 600.2526854066715, 608.5648761135809, 610.8533951851524, 621.7015832400473, 630.5806638585381, 542.3573065916822, 622.5051084387923, 636.6021236727046, 660.7125900233414, 662.493192707579, 8935.374523783403, 9327.860082098774, 9403.952980235055, 9527.758074667561, 9576.712698619052, 644.9161608990895, 651.1539784710399, 666.2504682232488, 681.536555142498, 708.2917082917083, 4381.868543943687, 4494.977525112354, 4651.348811166439, 4676.625869930397, 4763.04739052191, 78785.60607196928, 122962.0, 126412.0, 126887.0, 129781.0, 106733.0, 109257.0, 110248.4487577557, 110355.0, 110694.0, 58088.19118088157, 78837.0, 91975.67391509697, 94649.5267523658, 96303.51848240713, 8205.466644668077, 8756.124387561193, 8891.555422228734, 8920.107989201028, 9095.818083638422, 38400.80799595984, 49183.75408122937, 51996.220056698425, 52364.21453678274, 53155.0, 71747.0, 72910.63544682243, 79660.60169699346, 79843.60078199572, 82668.0, 11258.718532036764, 11523.481443334902, 11582.594609188638, 11606.419679015846, 11629.18595698293, 12480.126391152533, 12637.873621264038, 12839.229646221256, 12902.548410805577, 12912.870871291543, 13496.5276215332, 13773.311334433038, 14270.0, 14392.20842853653, 15340.92329538345, 13660.86339136596, 14155.221462819643, 14229.50196743109, 14688.0, 14804.185769782334, 13224.669383265495, 13908.582742517741, 14953.327100280296, 15078.095314281178, 15085.698286034438, 10997.12023038171, 11840.289582624726, 12066.939665301617, 12261.570845020382, 12589.559365422167, 10463.68608941733, 11792.0, 11953.760924781629, 12051.216670916363, 12445.75108497843], "value": 7552.303849275361, "unit": "score"}], "name": "dromaeo_css", "value": 6997.204533525957, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 111.0, 109.0, 110.0, 111.0, 111.0, 111.0, 112.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 122.0, 169.0, 122.0, 125.0, 122.0, 123.0, 154.0, 122.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 160.0, 161.0, 160.0, 162.0, 160.0, 161.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 111.0, 76.0, 76.0, 78.0, 75.0, 99.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 115.0, 81.0, 83.0, 81.0, 99.0, 81.0, 81.0, 118.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 52.0, 54.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 62.0, 62.0, 61.0, 62.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 87.0, 85.0, 79.0, 88.0, 78.0, 82.0, 79.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 128.0, 136.0, 133.0, 141.0, 131.0, 144.0, 174.0, 132.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 166.0, 171.0, 166.0, 165.0, 171.0, 170.0, 166.0, 165.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 69.0, 75.0, 66.0, 73.0, 66.0, 65.0, 66.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1502.9, "alertThreshold": 2.0}]} 09:25:45 INFO - Return code: 0 09:25:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:25:45 INFO - # TBPL SUCCESS # 09:25:45 INFO - Running post-action listener: _resource_record_post_action 09:25:45 INFO - [mozharness: 2016-09-30 16:25:45.666318Z] Finished run-tests step (success) 09:25:45 INFO - Running post-run listener: _resource_record_post_run 09:25:45 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392626176; Read time: 20; Write time: 251652 09:25:45 INFO - TinderboxPrint: CPU usage
13.1% 09:25:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 09:25:45 INFO - TinderboxPrint: I/O write bytes / time
392,626,176 / 251,652 09:25:45 INFO - TinderboxPrint: CPU idle
8,283.0 (86.9%) 09:25:45 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 09:25:45 INFO - TinderboxPrint: Swap in / out
0 / 0 09:25:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103100416; Read time: 0; Write time: 52252 09:25:45 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289525760; Read time: 0; Write time: 199400 09:25:46 INFO - Running post-run listener: _upload_blobber_files 09:25:46 INFO - Blob upload gear active. 09:25:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:25:46 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 09:25:46 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:25:46 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:25:46 INFO - (blobuploader) - INFO - Open directory for files ... 09:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:47 INFO - (blobuploader) - INFO - Done attempting. 09:25:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:25:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:25:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:51 INFO - (blobuploader) - INFO - Done attempting. 09:25:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:25:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:25:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:52 INFO - (blobuploader) - INFO - Done attempting. 09:25:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:25:52 INFO - Return code: 0 09:25:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:25:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:25:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2efbf4ffd173884beea9595616ab1b79a625bb9c8f54de97d86e6438b63f85b23be18e719db6576e2e2be09ee4099bd178857a5fdc25550020d708afd2bb7281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7f6b310b23fea2f044240dbb585019401ae93161e6c0a3cd3cb669ff7f29774be5401c267a48bf2b771225c71ed3f310ed94245d1cb5d0d75ae9b1eac5423fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca61da9e487167349b0fd167dc1e2959035ba77a4b2f881727d0c92c367210f626df61d4a2e8c13b668fe4e769c5f0a3801fc6513c4ad87dc046324bb82620d8"} 09:25:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:25:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:25:52 INFO - Contents: 09:25:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2efbf4ffd173884beea9595616ab1b79a625bb9c8f54de97d86e6438b63f85b23be18e719db6576e2e2be09ee4099bd178857a5fdc25550020d708afd2bb7281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7f6b310b23fea2f044240dbb585019401ae93161e6c0a3cd3cb669ff7f29774be5401c267a48bf2b771225c71ed3f310ed94245d1cb5d0d75ae9b1eac5423fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca61da9e487167349b0fd167dc1e2959035ba77a4b2f881727d0c92c367210f626df61d4a2e8c13b668fe4e769c5f0a3801fc6513c4ad87dc046324bb82620d8"} 09:25:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:25:52 INFO - Copying logs to upload dir... 09:25:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:25:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.612624 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-09-30 09:25:52.451030) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 09:25:52.454096) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2efbf4ffd173884beea9595616ab1b79a625bb9c8f54de97d86e6438b63f85b23be18e719db6576e2e2be09ee4099bd178857a5fdc25550020d708afd2bb7281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7f6b310b23fea2f044240dbb585019401ae93161e6c0a3cd3cb669ff7f29774be5401c267a48bf2b771225c71ed3f310ed94245d1cb5d0d75ae9b1eac5423fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca61da9e487167349b0fd167dc1e2959035ba77a4b2f881727d0c92c367210f626df61d4a2e8c13b668fe4e769c5f0a3801fc6513c4ad87dc046324bb82620d8"} build_url:https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011467 build_url: 'https://queue.taskcluster.net/v1/task/HjVC3P0kQIK7AKPfxxKWPA/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2efbf4ffd173884beea9595616ab1b79a625bb9c8f54de97d86e6438b63f85b23be18e719db6576e2e2be09ee4099bd178857a5fdc25550020d708afd2bb7281", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7f6b310b23fea2f044240dbb585019401ae93161e6c0a3cd3cb669ff7f29774be5401c267a48bf2b771225c71ed3f310ed94245d1cb5d0d75ae9b1eac5423fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca61da9e487167349b0fd167dc1e2959035ba77a4b2f881727d0c92c367210f626df61d4a2e8c13b668fe4e769c5f0a3801fc6513c4ad87dc046324bb82620d8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 09:25:52.479627) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:25:52.479993) ========= 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=a0d2d2de893abaa8702a9954000001a7-1475250430.601270-1990050673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 09:25:52.563510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 09:25:52.563880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 09:25:52.564278) ========= ========= Total master_lag: 0.30 =========