builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1475166631.47 results: success (0) buildid: 20160929063004 builduid: 0859d89aeb9e4e5fbc06383e529ec608 revision: 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.472588) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.473117) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.473426) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.488903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.489316) ========= 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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-29 09:30:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.0M=0s 2016-09-29 09:30:32 (67.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149146 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.654776) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.655373) ========= 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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016675 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:31.707478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 09:30:31.707940) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 --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 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 --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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-29 09:30:32,349 truncating revision to first 12 chars 2016-09-29 09:30:32,349 Setting DEBUG logging. 2016-09-29 09:30:32,349 attempt 1/10 2016-09-29 09:30:32,349 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/215adbcb1987?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 09:30:33,531 unpacking tar archive at: autoland-215adbcb1987/testing/mozharness/ program finished with exit code 0 elapsedTime=1.517352 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 09:30:33.239821) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:33.240309) ========= script_repo_revision: 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:33.240908) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:33.241203) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 09:30:33.252355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-29 09:30:33.252797) ========= /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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:33 INFO - MultiFileLogger online at 20160929 09:30:33 in /builds/slave/test 09:30:33 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:30:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:33 INFO - {'append_to_log': False, 09:30:33 INFO - 'base_work_dir': '/builds/slave/test', 09:30:33 INFO - 'blob_upload_branch': 'Autoland', 09:30:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:33 INFO - 'branch': 'Autoland', 09:30:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:33 INFO - 'config_files': ('talos/linux_config.py',), 09:30:33 INFO - 'default_actions': ('clobber', 09:30:33 INFO - 'read-buildbot-config', 09:30:33 INFO - 'download-and-extract', 09:30:33 INFO - 'populate-webroot', 09:30:33 INFO - 'create-virtualenv', 09:30:33 INFO - 'install', 09:30:33 INFO - 'run-tests'), 09:30:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:33 INFO - 'download_minidump_stackwalk': True, 09:30:33 INFO - 'download_symbols': 'ondemand', 09:30:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:33 INFO - 'installer_path': 'installer.exe', 09:30:33 INFO - 'log_level': 'info', 09:30:33 INFO - 'log_name': 'talos', 09:30:33 INFO - 'log_to_console': True, 09:30:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:33 INFO - 'opt_config_files': (), 09:30:33 INFO - 'pip_index': False, 09:30:33 INFO - 'sps_profile': False, 09:30:33 INFO - 'sps_profile_interval': 0, 09:30:33 INFO - 'suite': 'dromaeojs', 09:30:33 INFO - 'system_bits': '32', 09:30:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:33 INFO - 'title': 'talos-linux64-ix-035', 09:30:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:33 INFO - 'use_talos_json': True, 09:30:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:33 INFO - 'work_dir': 'build'} 09:30:33 INFO - [mozharness: 2016-09-29 16:30:33.987833Z] Running clobber step. 09:30:33 INFO - Running pre-action listener: _resource_record_pre_action 09:30:33 INFO - Running main action method: clobber 09:30:33 INFO - rmtree: /builds/slave/test/build 09:30:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:34 INFO - Running post-action listener: _resource_record_post_action 09:30:34 INFO - [mozharness: 2016-09-29 16:30:34.967004Z] Finished clobber step (success) 09:30:34 INFO - [mozharness: 2016-09-29 16:30:34.967105Z] Running read-buildbot-config step. 09:30:34 INFO - Running pre-action listener: _resource_record_pre_action 09:30:34 INFO - Running main action method: read_buildbot_config 09:30:34 INFO - Using buildbot properties: 09:30:34 INFO - { 09:30:34 INFO - "project": "", 09:30:34 INFO - "product": "firefox", 09:30:34 INFO - "script_repo_revision": "production", 09:30:34 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:30:34 INFO - "repository": "", 09:30:34 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 09:30:34 INFO - "buildid": "20160929063004", 09:30:34 INFO - "pgo_build": "True", 09:30:34 INFO - "basedir": "/builds/slave/test", 09:30:34 INFO - "buildnumber": 269, 09:30:34 INFO - "slavename": "talos-linux64-ix-035", 09:30:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:30:34 INFO - "platform": "ubuntu64_hw", 09:30:34 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 09:30:34 INFO - "branch": "autoland", 09:30:34 INFO - "repo_path": "integration/autoland", 09:30:34 INFO - "revision": "215adbcb1987f3a8e065f4c92a3d4215d3f93dd1", 09:30:34 INFO - "stage_platform": "linux64-pgo", 09:30:34 INFO - "builduid": "0859d89aeb9e4e5fbc06383e529ec608", 09:30:34 INFO - "slavebuilddir": "test" 09:30:34 INFO - } 09:30:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:30:34 INFO - Running post-action listener: _resource_record_post_action 09:30:34 INFO - [mozharness: 2016-09-29 16:30:34.969562Z] Finished read-buildbot-config step (success) 09:30:34 INFO - [mozharness: 2016-09-29 16:30:34.969652Z] Running download-and-extract step. 09:30:34 INFO - Running pre-action listener: _resource_record_pre_action 09:30:34 INFO - Running main action method: download_and_extract 09:30:34 INFO - mkdir: /builds/slave/test/build/tests 09:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:34 INFO - trying https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:30:34 INFO - Downloading https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/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:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/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:30:35 INFO - Downloaded 1361 bytes. 09:30:35 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:30:35 INFO - Using the following test package requirements: 09:30:35 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:30:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:30:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:30:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'jsshell-linux-x86_64.zip'], 09:30:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:30:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:30:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:30:35 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:30:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:30:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:30:35 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:30:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:30:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:30:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:35 INFO - Fetch https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:30:36 INFO - Http code: 200 09:30:36 INFO - Content-Encoding: None 09:30:36 INFO - Content-Type: application/zip 09:30:36 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 09:30:36 INFO - x-amz-cf-id: riEzAwWmlItGlhznoHNn9qqUQwze1aK43If_aPjn6TT1TxderKDD8A== 09:30:36 INFO - x-amz-version-id: 6e9l_SvxD91RzorfZd0OOSRaC77VIUK. 09:30:36 INFO - x-cache: Hit from cloudfront 09:30:36 INFO - Expected file size: 21989138 09:30:36 INFO - Obtained file size: 21989138 09:30:40 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:30:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:30:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:40 INFO - Fetch https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:30:41 INFO - Http code: 200 09:30:41 INFO - Content-Encoding: None 09:30:41 INFO - Content-Type: application/zip 09:30:41 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 09:30:41 INFO - x-amz-cf-id: ItweeyPfPuO3TEX-pF_-K1eoy-XtTjnIgb4ihku_xixDnytMqmlrNg== 09:30:41 INFO - x-amz-version-id: 6e9l_SvxD91RzorfZd0OOSRaC77VIUK. 09:30:41 INFO - x-cache: Hit from cloudfront 09:30:41 INFO - Expected file size: 21989138 09:30:41 INFO - Obtained file size: 21989138 09:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:30:46 INFO - Http code: 200 09:30:46 INFO - Content-Encoding: None 09:30:46 INFO - Content-Type: application/zip 09:30:46 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 09:30:46 INFO - x-amz-cf-id: jyzVl9n7E9GsV7I5ic6PG9O3FBQQOH0Z4JYWTSea_JEE71lVWUCuSA== 09:30:46 INFO - x-amz-version-id: nuF7ZH2Nkhqg9PRdlnS_MG20MR0AJTyO 09:30:46 INFO - x-cache: Hit from cloudfront 09:30:46 INFO - Expected file size: 14414067 09:30:46 INFO - Obtained file size: 14414067 09:30:46 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:30:46 INFO - trying https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:30:46 INFO - Downloading https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/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:30:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/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:30:47 INFO - Downloaded 59739965 bytes. 09:30:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:30:47 INFO - mkdir: /builds/slave/test/properties 09:30:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:47 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:47 INFO - Contents: 09:30:47 INFO - build_url:https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:30:48 INFO - Running post-action listener: _resource_record_post_action 09:30:48 INFO - Running post-action listener: set_extra_try_arguments 09:30:48 INFO - [mozharness: 2016-09-29 16:30:48.488543Z] Finished download-and-extract step (success) 09:30:48 INFO - [mozharness: 2016-09-29 16:30:48.488770Z] Running populate-webroot step. 09:30:48 INFO - Running pre-action listener: _resource_record_pre_action 09:30:48 INFO - Running main action method: populate_webroot 09:30:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:48 INFO - '--tppagecycles', 09:30:48 INFO - '7'], 09:30:48 INFO - 'tests': ['tsvgm']}}, 09:30:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['damp', 'tps']}, 09:30:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'tests': ['damp', 'tps']}, 09:30:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['dromaeo_dom']}, 09:30:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['basic_compositor_video']}, 09:30:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:30:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['a11yr', 09:30:48 INFO - 'ts_paint', 09:30:48 INFO - 'tpaint', 09:30:48 INFO - 'sessionrestore', 09:30:48 INFO - 'sessionrestore_no_auto_restore', 09:30:48 INFO - 'tabpaint']}, 09:30:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['tsvgx', 09:30:48 INFO - 'tsvgr_opacity', 09:30:48 INFO - 'tart', 09:30:48 INFO - 'tscrollx', 09:30:48 INFO - 'cart']}, 09:30:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:48 INFO - 'tsvgr_opacity', 09:30:48 INFO - 'tart', 09:30:48 INFO - 'tscrollx', 09:30:48 INFO - 'cart']}, 09:30:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'talos_options': ['--disable-e10s'], 09:30:48 INFO - 'tests': ['tp5o']}, 09:30:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'tests': ['tp5o']}, 09:30:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'talos_options': ['--disable-e10s', 09:30:48 INFO - '--xperf_path', 09:30:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:48 INFO - 'tests': ['tp5n']}, 09:30:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:48 INFO - 'talos_options': ['--xperf_path', 09:30:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:48 INFO - 'tests': ['tp5n']}}, 09:30:48 INFO - 'talos.zip': {'path': '', 09:30:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:48 INFO - Running post-action listener: _resource_record_post_action 09:30:48 INFO - [mozharness: 2016-09-29 16:30:48.504035Z] Finished populate-webroot step (success) 09:30:48 INFO - [mozharness: 2016-09-29 16:30:48.504124Z] Running create-virtualenv step. 09:30:48 INFO - Running pre-action listener: _resource_record_pre_action 09:30:48 INFO - Running main action method: create_virtualenv 09:30:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:48 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:30:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:48 INFO - Using real prefix '/usr' 09:30:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:51 INFO - Installing pip.................done. 09:30:51 INFO - Return code: 0 09:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:30:51 INFO - Reading from file tmpfile_stdout 09:30:51 INFO - Output received: 09:30:51 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:30:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:51 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:51 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:30:51 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:30:51 INFO - Using env: {'DISPLAY': ':0', 09:30:51 INFO - 'HOME': '/home/cltbld', 09:30:51 INFO - 'LANG': 'en_US.UTF-8', 09:30:51 INFO - 'LANGUAGE': 'en_US:en', 09:30:51 INFO - 'LOGNAME': 'cltbld', 09:30:51 INFO - 'MAIL': '/var/mail/cltbld', 09:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:51 INFO - 'MOZ_NO_REMOTE': '1', 09:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:51 INFO - 'NO_EM_RESTART': '1', 09:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:51 INFO - 'PWD': '/builds/slave/test', 09:30:51 INFO - 'SHELL': '/bin/bash', 09:30:51 INFO - 'SHLVL': '1', 09:30:51 INFO - 'TERM': 'linux', 09:30:51 INFO - 'TMOUT': '86400', 09:30:51 INFO - 'USER': 'cltbld', 09:30:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:51 INFO - '_': '/tools/buildbot/bin/python'} 09:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:51 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:30:51 INFO - Cleaning up... 09:30:51 INFO - Return code: 0 09:30:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:51 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:51 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:30:51 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:30:51 INFO - Using env: {'DISPLAY': ':0', 09:30:51 INFO - 'HOME': '/home/cltbld', 09:30:51 INFO - 'LANG': 'en_US.UTF-8', 09:30:51 INFO - 'LANGUAGE': 'en_US:en', 09:30:51 INFO - 'LOGNAME': 'cltbld', 09:30:51 INFO - 'MAIL': '/var/mail/cltbld', 09:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:51 INFO - 'MOZ_NO_REMOTE': '1', 09:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:51 INFO - 'NO_EM_RESTART': '1', 09:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:51 INFO - 'PWD': '/builds/slave/test', 09:30:51 INFO - 'SHELL': '/bin/bash', 09:30:51 INFO - 'SHLVL': '1', 09:30:51 INFO - 'TERM': 'linux', 09:30:51 INFO - 'TMOUT': '86400', 09:30:51 INFO - 'USER': 'cltbld', 09:30:51 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:51 INFO - '_': '/tools/buildbot/bin/python'} 09:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:51 INFO - Downloading/unpacking psutil>=3.1.1 09:30:51 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:30:51 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:30:51 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:30:51 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:30:51 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:30:51 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:30:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:53 INFO - Installing collected packages: psutil 09:30:53 INFO - Running setup.py install for psutil 09:30:53 INFO - building 'psutil._psutil_linux' extension 09:30:53 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:30:53 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:30:54 INFO - building 'psutil._psutil_posix' extension 09:30:54 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:30:54 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:30:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:54 INFO - Successfully installed psutil 09:30:54 INFO - Cleaning up... 09:30:54 INFO - Return code: 0 09:30:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30:54 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:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:54 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:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:54 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:54 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:30:54 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:30:54 INFO - Using env: {'DISPLAY': ':0', 09:30:54 INFO - 'HOME': '/home/cltbld', 09:30:54 INFO - 'LANG': 'en_US.UTF-8', 09:30:54 INFO - 'LANGUAGE': 'en_US:en', 09:30:54 INFO - 'LOGNAME': 'cltbld', 09:30:54 INFO - 'MAIL': '/var/mail/cltbld', 09:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:54 INFO - 'MOZ_NO_REMOTE': '1', 09:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:54 INFO - 'NO_EM_RESTART': '1', 09:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:54 INFO - 'PWD': '/builds/slave/test', 09:30:54 INFO - 'SHELL': '/bin/bash', 09:30:54 INFO - 'SHLVL': '1', 09:30:54 INFO - 'TERM': 'linux', 09:30:54 INFO - 'TMOUT': '86400', 09:30:54 INFO - 'USER': 'cltbld', 09:30:54 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:54 INFO - '_': '/tools/buildbot/bin/python'} 09:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:54 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:30:54 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:30:54 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:30:54 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:30:54 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:30:54 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:30:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:56 INFO - Installing collected packages: mozsystemmonitor 09:30:56 INFO - Running setup.py install for mozsystemmonitor 09:30:56 INFO - Successfully installed mozsystemmonitor 09:30:56 INFO - Cleaning up... 09:30:56 INFO - Return code: 0 09:30:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:56 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:30:56 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:30:56 INFO - Using env: {'DISPLAY': ':0', 09:30:56 INFO - 'HOME': '/home/cltbld', 09:30:56 INFO - 'LANG': 'en_US.UTF-8', 09:30:56 INFO - 'LANGUAGE': 'en_US:en', 09:30:56 INFO - 'LOGNAME': 'cltbld', 09:30:56 INFO - 'MAIL': '/var/mail/cltbld', 09:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:56 INFO - 'MOZ_NO_REMOTE': '1', 09:30:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:56 INFO - 'NO_EM_RESTART': '1', 09:30:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:56 INFO - 'PWD': '/builds/slave/test', 09:30:56 INFO - 'SHELL': '/bin/bash', 09:30:56 INFO - 'SHLVL': '1', 09:30:56 INFO - 'TERM': 'linux', 09:30:56 INFO - 'TMOUT': '86400', 09:30:56 INFO - 'USER': 'cltbld', 09:30:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:56 INFO - '_': '/tools/buildbot/bin/python'} 09:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:56 INFO - Downloading/unpacking jsonschema==2.5.1 09:30:56 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:30:56 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:30:56 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:30:56 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:30:56 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:30:56 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:30:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:30:58 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:30:58 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:30:58 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:30:58 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:30:58 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:30:58 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:30:58 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:59 INFO - warning: no files found matching '*.txt' 09:30:59 INFO - no previously-included directories found matching 'build' 09:30:59 INFO - no previously-included directories found matching 'dist' 09:30:59 INFO - no previously-included directories found matching '.git*' 09:30:59 INFO - Installing collected packages: jsonschema, functools32 09:30:59 INFO - Running setup.py install for functools32 09:30:59 INFO - warning: no files found matching '*.txt' 09:30:59 INFO - no previously-included directories found matching 'build' 09:30:59 INFO - no previously-included directories found matching 'dist' 09:30:59 INFO - no previously-included directories found matching '.git*' 09:30:59 INFO - Successfully installed jsonschema functools32 09:30:59 INFO - Cleaning up... 09:30:59 INFO - Return code: 0 09:30:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:30:59 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:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 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:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:59 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:30:59 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:30:59 INFO - Using env: {'DISPLAY': ':0', 09:30:59 INFO - 'HOME': '/home/cltbld', 09:30:59 INFO - 'LANG': 'en_US.UTF-8', 09:30:59 INFO - 'LANGUAGE': 'en_US:en', 09:30:59 INFO - 'LOGNAME': 'cltbld', 09:30:59 INFO - 'MAIL': '/var/mail/cltbld', 09:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:59 INFO - 'MOZ_NO_REMOTE': '1', 09:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:59 INFO - 'NO_EM_RESTART': '1', 09:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:59 INFO - 'PWD': '/builds/slave/test', 09:30:59 INFO - 'SHELL': '/bin/bash', 09:30:59 INFO - 'SHLVL': '1', 09:30:59 INFO - 'TERM': 'linux', 09:30:59 INFO - 'TMOUT': '86400', 09:30:59 INFO - 'USER': 'cltbld', 09:30:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:59 INFO - '_': '/tools/buildbot/bin/python'} 09:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:30:59 INFO - Cleaning up... 09:30:59 INFO - Return code: 0 09:30:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:59 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:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 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:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:59 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:30:59 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:30:59 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:30:59 INFO - Using env: {'DISPLAY': ':0', 09:30:59 INFO - 'HOME': '/home/cltbld', 09:30:59 INFO - 'LANG': 'en_US.UTF-8', 09:30:59 INFO - 'LANGUAGE': 'en_US:en', 09:30:59 INFO - 'LOGNAME': 'cltbld', 09:30:59 INFO - 'MAIL': '/var/mail/cltbld', 09:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:59 INFO - 'MOZ_NO_REMOTE': '1', 09:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:30:59 INFO - 'NO_EM_RESTART': '1', 09:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:59 INFO - 'PWD': '/builds/slave/test', 09:30:59 INFO - 'SHELL': '/bin/bash', 09:30:59 INFO - 'SHLVL': '1', 09:30:59 INFO - 'TERM': 'linux', 09:30:59 INFO - 'TMOUT': '86400', 09:30:59 INFO - 'USER': 'cltbld', 09:30:59 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:59 INFO - '_': '/tools/buildbot/bin/python'} 09:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:59 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:30:59 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:30:59 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:30:59 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:30:59 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:30:59 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:31:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:01 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:31:01 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:31:01 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:31:01 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:31:01 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:31:01 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:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:02 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:31:02 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:31:02 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:31:02 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:31:02 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:31:02 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:31:02 INFO - Downloading docopt-0.6.1.tar.gz 09:31:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:02 INFO - Running setup.py install for blobuploader 09:31:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:02 INFO - Running setup.py install for requests 09:31:02 INFO - Running setup.py install for docopt 09:31:02 INFO - Successfully installed blobuploader requests docopt 09:31:02 INFO - Cleaning up... 09:31:02 INFO - Return code: 0 09:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:02 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:31:02 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:31:02 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:31:02 INFO - Using env: {'DISPLAY': ':0', 09:31:02 INFO - 'HOME': '/home/cltbld', 09:31:02 INFO - 'LANG': 'en_US.UTF-8', 09:31:02 INFO - 'LANGUAGE': 'en_US:en', 09:31:02 INFO - 'LOGNAME': 'cltbld', 09:31:02 INFO - 'MAIL': '/var/mail/cltbld', 09:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:02 INFO - 'MOZ_NO_REMOTE': '1', 09:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:02 INFO - 'NO_EM_RESTART': '1', 09:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:02 INFO - 'PWD': '/builds/slave/test', 09:31:02 INFO - 'SHELL': '/bin/bash', 09:31:02 INFO - 'SHLVL': '1', 09:31:02 INFO - 'TERM': 'linux', 09:31:02 INFO - 'TMOUT': '86400', 09:31:02 INFO - 'USER': 'cltbld', 09:31:02 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:02 INFO - '_': '/tools/buildbot/bin/python'} 09:31:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:03 INFO - Running setup.py (path:/tmp/pip-B1CkQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:03 INFO - Running setup.py (path:/tmp/pip-gqaw91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:03 INFO - Running setup.py (path:/tmp/pip-yqoDvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:03 INFO - Running setup.py (path:/tmp/pip-JOxCev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:03 INFO - Running setup.py (path:/tmp/pip-4NeDXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:03 INFO - Running setup.py (path:/tmp/pip-7_OIyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:03 INFO - Running setup.py (path:/tmp/pip-oU_g25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:03 INFO - Running setup.py (path:/tmp/pip-G5jz5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:03 INFO - Running setup.py (path:/tmp/pip-8j5tmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:03 INFO - Running setup.py (path:/tmp/pip-fREAz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:03 INFO - Running setup.py (path:/tmp/pip-cJhaCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:03 INFO - Running setup.py (path:/tmp/pip-_KG4JH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:04 INFO - Running setup.py (path:/tmp/pip-IQQA5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:04 INFO - Running setup.py (path:/tmp/pip-0w44uX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:04 INFO - Running setup.py (path:/tmp/pip-NX0rFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:04 INFO - Running setup.py (path:/tmp/pip-LYXNvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:04 INFO - Running setup.py (path:/tmp/pip-j4VsDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:04 INFO - Running setup.py install for manifestparser 09:31:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozcrash 09:31:04 INFO - Running setup.py install for mozdebug 09:31:04 INFO - Running setup.py install for mozdevice 09:31:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozfile 09:31:05 INFO - Running setup.py install for mozhttpd 09:31:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozinfo 09:31:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozInstall 09:31:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozleak 09:31:05 INFO - Running setup.py install for mozlog 09:31:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for moznetwork 09:31:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozprocess 09:31:05 INFO - Running setup.py install for mozprofile 09:31:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozrunner 09:31:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:06 INFO - Running setup.py install for mozscreenshot 09:31:06 INFO - Running setup.py install for moztest 09:31:06 INFO - Running setup.py install for mozversion 09:31:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:06 INFO - Cleaning up... 09:31:06 INFO - Return code: 0 09:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:31:06 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:31:06 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:31:06 INFO - Using env: {'DISPLAY': ':0', 09:31:06 INFO - 'HOME': '/home/cltbld', 09:31:06 INFO - 'LANG': 'en_US.UTF-8', 09:31:06 INFO - 'LANGUAGE': 'en_US:en', 09:31:06 INFO - 'LOGNAME': 'cltbld', 09:31:06 INFO - 'MAIL': '/var/mail/cltbld', 09:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:06 INFO - 'MOZ_NO_REMOTE': '1', 09:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:06 INFO - 'NO_EM_RESTART': '1', 09:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:06 INFO - 'PWD': '/builds/slave/test', 09:31:06 INFO - 'SHELL': '/bin/bash', 09:31:06 INFO - 'SHLVL': '1', 09:31:06 INFO - 'TERM': 'linux', 09:31:06 INFO - 'TMOUT': '86400', 09:31:06 INFO - 'USER': 'cltbld', 09:31:06 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:06 INFO - '_': '/tools/buildbot/bin/python'} 09:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:06 INFO - Running setup.py (path:/tmp/pip-nHTo7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:06 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:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 INFO - Running setup.py (path:/tmp/pip-wyAjiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 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:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 INFO - Running setup.py (path:/tmp/pip-uUNLFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 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:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 INFO - Running setup.py (path:/tmp/pip-UyOr5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 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:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:06 INFO - Running setup.py (path:/tmp/pip-A4sP1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:06 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:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:06 INFO - Running setup.py (path:/tmp/pip-dcEyxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:07 INFO - Running setup.py (path:/tmp/pip-e3jL6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:07 INFO - Running setup.py (path:/tmp/pip-OnGeH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:07 INFO - Running setup.py (path:/tmp/pip-qNzbRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:07 INFO - Running setup.py (path:/tmp/pip-CreAbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:07 INFO - Running setup.py (path:/tmp/pip-qynYnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 INFO - Running setup.py (path:/tmp/pip-4QBiuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 INFO - Running setup.py (path:/tmp/pip-Dr6gcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 INFO - Running setup.py (path:/tmp/pip-Yhc7NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:07 INFO - Running setup.py (path:/tmp/pip-rxYd_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:07 INFO - Running setup.py (path:/tmp/pip-ff3eX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:07 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:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:07 INFO - Running setup.py (path:/tmp/pip-7MN_zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:07 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:31:07 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:31:07 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:31:07 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:31:07 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:31:07 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:31:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:09 INFO - Downloading blessings-1.6.tar.gz 09:31:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:09 INFO - Installing collected packages: blessings 09:31:09 INFO - Running setup.py install for blessings 09:31:09 INFO - Successfully installed blessings 09:31:09 INFO - Cleaning up... 09:31:09 INFO - Return code: 0 09:31:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:10 INFO - Reading from file tmpfile_stdout 09:31:10 INFO - Current package versions: 09:31:10 INFO - argparse == 1.2.1 09:31:10 INFO - blessings == 1.6 09:31:10 INFO - blobuploader == 1.2.4 09:31:10 INFO - docopt == 0.6.1 09:31:10 INFO - functools32 == 3.2.3-2 09:31:10 INFO - jsonschema == 2.5.1 09:31:10 INFO - manifestparser == 1.1 09:31:10 INFO - mozInstall == 1.12 09:31:10 INFO - mozcrash == 0.17 09:31:10 INFO - mozdebug == 0.1 09:31:10 INFO - mozdevice == 0.48 09:31:10 INFO - mozfile == 1.2 09:31:10 INFO - mozhttpd == 0.7 09:31:10 INFO - mozinfo == 0.9 09:31:10 INFO - mozleak == 0.1 09:31:10 INFO - mozlog == 3.4 09:31:10 INFO - moznetwork == 0.27 09:31:10 INFO - mozprocess == 0.23 09:31:10 INFO - mozprofile == 0.28 09:31:10 INFO - mozrunner == 6.12 09:31:10 INFO - mozscreenshot == 0.1 09:31:10 INFO - mozsystemmonitor == 0.3 09:31:10 INFO - moztest == 0.7 09:31:10 INFO - mozversion == 1.4 09:31:10 INFO - psutil == 3.1.1 09:31:10 INFO - requests == 1.2.3 09:31:10 INFO - wsgiref == 0.1.2 09:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:10 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:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:31:10 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:31:10 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:31:10 INFO - Using env: {'DISPLAY': ':0', 09:31:10 INFO - 'HOME': '/home/cltbld', 09:31:10 INFO - 'LANG': 'en_US.UTF-8', 09:31:10 INFO - 'LANGUAGE': 'en_US:en', 09:31:10 INFO - 'LOGNAME': 'cltbld', 09:31:10 INFO - 'MAIL': '/var/mail/cltbld', 09:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:10 INFO - 'MOZ_NO_REMOTE': '1', 09:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:10 INFO - 'NO_EM_RESTART': '1', 09:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:10 INFO - 'PWD': '/builds/slave/test', 09:31:10 INFO - 'SHELL': '/bin/bash', 09:31:10 INFO - 'SHLVL': '1', 09:31:10 INFO - 'TERM': 'linux', 09:31:10 INFO - 'TMOUT': '86400', 09:31:10 INFO - 'USER': 'cltbld', 09:31:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:10 INFO - '_': '/tools/buildbot/bin/python'} 09:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 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:31:10 INFO - Cleaning up... 09:31:10 INFO - Return code: 0 09:31:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31:10 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:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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 0x7fe150534f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ea27b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, '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': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', '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:31:10 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:31:10 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:31:10 INFO - Using env: {'DISPLAY': ':0', 09:31:10 INFO - 'HOME': '/home/cltbld', 09:31:10 INFO - 'LANG': 'en_US.UTF-8', 09:31:10 INFO - 'LANGUAGE': 'en_US:en', 09:31:10 INFO - 'LOGNAME': 'cltbld', 09:31:10 INFO - 'MAIL': '/var/mail/cltbld', 09:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:10 INFO - 'MOZ_NO_REMOTE': '1', 09:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:10 INFO - 'NO_EM_RESTART': '1', 09:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:10 INFO - 'PWD': '/builds/slave/test', 09:31:10 INFO - 'SHELL': '/bin/bash', 09:31:10 INFO - 'SHLVL': '1', 09:31:10 INFO - 'TERM': 'linux', 09:31:10 INFO - 'TMOUT': '86400', 09:31:10 INFO - 'USER': 'cltbld', 09:31:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:10 INFO - '_': '/tools/buildbot/bin/python'} 09:31:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:31:10 INFO - Cleaning up... 09:31:10 INFO - Return code: 0 09:31:10 INFO - Running post-action listener: _resource_record_post_action 09:31:10 INFO - Running post-action listener: _start_resource_monitoring 09:31:10 INFO - Starting resource monitoring. 09:31:10 INFO - [mozharness: 2016-09-29 16:31:10.697356Z] Finished create-virtualenv step (success) 09:31:10 INFO - [mozharness: 2016-09-29 16:31:10.697818Z] Running install step. 09:31:10 INFO - Running pre-action listener: _resource_record_pre_action 09:31:10 INFO - Running main action method: install 09:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:10 INFO - Reading from file tmpfile_stdout 09:31:10 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:10 INFO - Reading from file tmpfile_stdout 09:31:10 INFO - Output received: 09:31:10 INFO - Usage: mozinstall [options] installer 09:31:10 INFO - Options: 09:31:10 INFO - -h, --help show this help message and exit 09:31:10 INFO - -d DEST, --destination=DEST 09:31:10 INFO - Directory to install application into. [default: 09:31:10 INFO - "/builds/slave/test"] 09:31:10 INFO - --app=APP Application being installed. [default: firefox] 09:31:10 INFO - mkdir: /builds/slave/test/build/application 09:31:10 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:31:10 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:31:27 INFO - Reading from file tmpfile_stdout 09:31:27 INFO - Output received: 09:31:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:31:27 INFO - Running post-action listener: _resource_record_post_action 09:31:27 INFO - [mozharness: 2016-09-29 16:31:27.992855Z] Finished install step (success) 09:31:27 INFO - [mozharness: 2016-09-29 16:31:27.993101Z] Running run-tests step. 09:31:27 INFO - Running pre-action listener: _resource_record_pre_action 09:31:27 INFO - Running main action method: run_tests 09:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:27 INFO - Python 2.7.3 09:31:28 INFO - Return code: 0 09:31:28 INFO - grabbing minidump binary from tooltool 09:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:28 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 0x1f31790>, '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 0x1f32690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:28 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:31:28 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:31:28 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:31:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:28 INFO - Return code: 0 09:31:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:31:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:31:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:28 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/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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:31:28 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/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --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:31:28 INFO - Using env: {'DISPLAY': ':0', 09:31:28 INFO - 'HOME': '/home/cltbld', 09:31:28 INFO - 'LANG': 'en_US.UTF-8', 09:31:28 INFO - 'LANGUAGE': 'en_US:en', 09:31:28 INFO - 'LOGNAME': 'cltbld', 09:31:28 INFO - 'MAIL': '/var/mail/cltbld', 09:31:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:31:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:28 INFO - 'MOZ_NO_REMOTE': '1', 09:31:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:28 INFO - 'NO_EM_RESTART': '1', 09:31:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:28 INFO - 'PWD': '/builds/slave/test', 09:31:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:28 INFO - 'SHELL': '/bin/bash', 09:31:28 INFO - 'SHLVL': '1', 09:31:28 INFO - 'TERM': 'linux', 09:31:28 INFO - 'TMOUT': '86400', 09:31:28 INFO - 'USER': 'cltbld', 09:31:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919', 09:31:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:28 INFO - '_': '/tools/buildbot/bin/python'} 09:31:28 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/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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:31:28 INFO - mozversion application_buildid: 20160929063004 09:31:28 INFO - mozversion application_changeset: 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 09:31:28 INFO - mozversion application_display_name: Nightly 09:31:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:28 INFO - mozversion application_name: Firefox 09:31:28 INFO - mozversion application_remotingname: firefox 09:31:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:31:28 INFO - mozversion application_vendor: Mozilla 09:31:28 INFO - mozversion application_version: 52.0a1 09:31:28 INFO - mozversion platform_buildid: 20160929063004 09:31:28 INFO - mozversion platform_changeset: 215adbcb1987f3a8e065f4c92a3d4215d3f93dd1 09:31:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:31:28 INFO - mozversion platform_version: 52.0a1 09:31:28 INFO - using testdate: 1475166688 09:31:28 INFO - actual date: 1475166688 09:31:28 INFO - starting webserver on 'localhost:37791' 09:31:28 INFO - SUITE-START | Running 2 tests 09:31:28 INFO - TEST-START | dromaeo_css 09:31:28 INFO - operating with platform_type : linux_ 09:31:28 INFO - Initialising browser for dromaeo_css test... 09:31:28 INFO - TEST-INFO | started process 12968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprvyQf7/profile http://localhost:37791/getInfo.html) 09:31:32 INFO - PROCESS | 12968 | __metrics Screen width/height:1600/1200 09:31:32 INFO - PROCESS | 12968 | colorDepth:24 09:31:32 INFO - PROCESS | 12968 | Browser inner width/height: 1024/697 09:31:32 INFO - PROCESS | 12968 | __metrics 09:31:33 INFO - PROCESS | 12968 | 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:31:33 INFO - PROCESS | 12968 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:33 INFO - PROCESS | 12968 | console.error: PushService: 09:31:33 INFO - PROCESS | 12968 | stateChangeProcessEnqueue: Error transitioning state 09:31:33 INFO - PROCESS | 12968 | UnknownError 09:31:34 INFO - TEST-INFO | 12968: exit 0 09:31:34 INFO - Browser initialized. 09:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:34 INFO - TEST-INFO | started process 13052 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprvyQf7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:46 INFO - PROCESS | 13052 | RSS: Main: 173834240 09:31:46 INFO - PROCESS | 13052 | 09:32:37 INFO - PROCESS | 13052 | 1475166757971 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:34:47 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37791/tests/dromaeo/cssquery-ext.html) 09:34:48 INFO - PROCESS | 13052 | RSS: Main: 311500800 09:34:48 INFO - PROCESS | 13052 | 09:37:50 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-ext.html (next: http://localhost:37791/tests/dromaeo/cssquery-jquery.html) 09:37:51 INFO - PROCESS | 13052 | RSS: Main: 279318528 09:37:51 INFO - PROCESS | 13052 | 09:40:53 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37791/tests/dromaeo/cssquery-mootools.html) 09:40:53 INFO - PROCESS | 13052 | RSS: Main: 248766464 09:40:53 INFO - PROCESS | 13052 | 09:43:55 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37791/tests/dromaeo/cssquery-prototype.html) 09:43:56 INFO - PROCESS | 13052 | RSS: Main: 257093632 09:43:56 INFO - PROCESS | 13052 | 09:46:59 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37791/tests/dromaeo/cssquery-yui.html) 09:46:59 INFO - PROCESS | 13052 | RSS: Main: 222449664 09:46:59 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | Cycle 1(1): loaded http://localhost:37791/tests/dromaeo/cssquery-yui.html (next: http://localhost:37791/tests/dromaeo/cssquery-dojo.html) 09:50:02 INFO - PROCESS | 13052 | RSS: Main: 309624832 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | __start_tp_report 09:50:02 INFO - PROCESS | 13052 | _x_x_mozilla_page_load 09:50:02 INFO - PROCESS | 13052 | _x_x_mozilla_page_load_details 09:50:02 INFO - PROCESS | 13052 | |i|pagename|runs| 09:50:02 INFO - PROCESS | 13052 | |0;dojo.html;2965.6886026967168;2986.970130298699;2991.6110905582273;3016;3026.954595681064;5199.272101905736;5255.737213139342;5301;5328.200769884519;5367.168088946215;5180.067587834188;5252.448492908247;5275.709835959021;5347.491988261118;5384.946150538493;4837.637177211705;4846.9515304846855;4902.485239049902;4905.705657660552;4923.55687988081;4841.491643377448;4945.060438516688;4952.257161425779;4958.900821983558;5012.649114561982;4895.608351331921;4902.166631672589;4929.137400954836;4950.55445009949;4996.5503104720565;4787.970586323922;4805.519448055166;4828.275758636198;4855.028994201139;4855.4901735317635;4713.5522125398375;4715.528447155257;4721.811127554894;4722.1028004679;4788.233882578771;5213.504717051874;5254.395744489394;5264.894702105975;5283.260343551906;5288.735563221862;5482.698451585161;5529.861753456155;5564.4713752193475;5587.441255874421;5606.103023516218;5630.436956304377;5666.150077488369;5672.432756724335;5678.318601767773;5679.062954612484;5273.947260527384;5322.600804939645;5323.254744335834;5324.227986941872;5378.515933565998;5592.804251851166;5741;5751.539876809845;5815.680137592389;5842.357340692521;5594.916076258859;5597.832065038056;5602.52378547828;5653.434656534314;5671.177679236487;5671.177679236487;5682.119271512922;5682.374938756733;5684.54523638108;5685.744141513642;5441.075017247077;5459.836204913859;5466;5622.690752008679;5625.324961004706;5503.394626591072;5538.030844602178;5539.279893613804;5597.272354593958;5601.691906945157;4265.402843601898;4305.913881722348;4306;4307.892302692395;4353.499347575006;3823.4264860271182;3838.692904567684;3862.748921320105;3871.8838434847;3872.341701910682;5331.786728530816;5360.249565060894;5380.838574842761;5415.620906536584;5480.862978425571;5712.11462223356;5713.4000929902595;5717.599768016282;5718.428157184248;5725.570582206268;5283.709395983258;5404;5414.8104816332025;5417.729113544307;5476.808311709071;4819.277108433763;4832.251001094836;4855;4860.6111511079025;4899.706017638953;5866.472017518358;5868.677222752619;5881.441263080001;5935.139404786281;5941.524678025834;5597.80407685729;5651;5651.915221271602;5653.123765816305;5663.348714897839;5739;5739.483446489836;5856.355800861987;5858.004139296245;5861.0915308127305;5522.447755224445;5563.165525171256;5583.385827559046;5606;5660.773569057192;5694.686792226467;5701.6864072474755;5708.80019199326;5713.371529131875;5714.942850571441;5349;5551.555875530029;5561.721913904369;5562.694051827189;5565.332160140809;5443.918341224806;5535.308086489189;5600.35595906476;5604.411536788579;5671.546276297969;5541.667499950016;5587.050201465842;5589.888202236014;5590.385057643737;5593.105103183634;5602.327720673546;5611.354694210218;5616.354119276336;5630.18362337467;5633.0705433603;5343.812966546152;5384.9192262116885;5389.245505629294;5443.59173062014;5445.210444485607 09:50:02 INFO - PROCESS | 13052 | |1;ext.html;556.0519314568661;591.1516973143539;598.2671227746009;605.772835186805;617.4042049422308;31835.68164318356;42838.14323713528;43011.56988430115;43113.35329970045;43138.352924706116;44051.3392299115;44346.778266108624;44446;44487.55512444874;44523.77738111305;8127.642983534768;8800.8679869802;8948.373613847034;8954;8959.14888085634;8076.8788468173025;10439.739006524822;10626.946865265663;10658;11072.224944253905;10885;10942.23404361695;10945.124390048817;10954.39750813705;10954.671359859218;10824.78350432991;10892.782144357148;10893.945530272338;10902.400367979759;10914;10242.231832612584;10256.692299231036;10316.535755891004;10319;10324.845127323097;42126.78936605328;42255.57744422578;42422.57577424217;42574.36138457926;42594.57405425937;3359.2777552826014;3494.3884820156627;3701;4058.9594104059174;4176.310908700061;31932.840335798406;32860.34279314424;32905.01284961455;33345.6665433345;33526.32947341064;4102.569230230843;4193.790310484464;4232.534421213664;4352.107817897323;4440.2895536714295;367348;368040;369808;370339;372040;56552.717236413555;56881.431185687616;56886.71556642273;57009.14486282709;57292;59039.70480147572;59176.704116479144;59366.10950835741;59386.70306648439;59499.70250148721;13859.653508662364;13950.581482555539;13952.232627205602;14110.294485275901;14125.929370353082;10295.742606434898;10321.74195645115;10660.62687805923;10964.56141754321;10979.560817567208;15566;16131.274092665859;16155.757663635055;16173.595660108356;16206;6208.627482351073;6497.180789378982;6575.80272591823;6734.595924244561;6735.191776986695;901.8647202919483;1003.6437064841876;1065.4193464561833;1082.3938594387168;1141.7145713571608;31351.529727054112;31352.21619459532;31434;32096.518552221736;32347.191320216712;1078.3745427651943;1087.798757229915;1113.059464752283;1137.5279259107542;1141.372245265101;16084.437044703613;16728.916355418387;16855.241514131892;17022.39449604931;17047.48857534276;501686;521747;523239.8380080981;523251;524717;47823.76088119537;53918.7304063485;55482.722586387616;55487.445125549035;60931.39068609257;1295.7019885426405;1301.1867582760774;1324.854266030755;1333.1268019447277;1333.2134040915905;30167.21874126994;33074.83462582672;33115.66884331126;33219.33561328808;33356.833215833765;50395;51078;51084.23373649324;51136.23295650494;51232;469196;469882;470489;471028;471102;38605.22789544137;39945.60054399419;39955.60044399635;40000;40284.39573406343;46460;46883;47010.05979880452;47181.29228061651;47457;39582.604173957894;39917;40647.39028914628;40654;41855;38957.41563876601;39719.40420893747;40463.59536404716;40843;42074.57925420706;40178.1964360717;41011;41116.1776764469;41138.588614113476;41479;34500.30999380049;35494.64505354913;35658.28683426369;35884.28231435409;36586.26827463489;27534.44931101407;27782.722172778012;27866.16401507958;27951;28043.57934631023 09:50:02 INFO - PROCESS | 13052 | |2;jquery.html;375.14092727653673;382.8239010055378;403.1030956122627;403.6084997552375;413.734801959273;13171.670708232286;14847;15813.683726325467;18776.12207010889;18883.905580472114;17294.13529323535;17405.738913916302;17681.64636707265;17790.911045444787;18184.181711822966;5341.225522299274;5619.5785316101255;5623.971880140593;5624.690642014688;5627.915581266284;5421.566274698034;5541.5289700375515;5556.77772889086;5603.915941260884;5611.859703507404;5596.188552659862;5603.439656034405;5607.271054762875;5609.102543593026;5617.747201375927;5431.158170483582;5491.725413729318;5492.286002819648;5510.338759348904;5511.145772405283;4970.2793095001025;4972.707096155026;4973.5523802857915;4975;4976.0545496355935;22430;22529.395296752442;22639.547209055727;22669.77330226693;22682.319530414115;2119.3747844385944;2155.5581105873257;2181.3128864407818;2237.127520267097;2468.7654672806057;13963.301834908214;14219;14272.644098810597;14346.64133396663;14931.626709332353;1803.2156012134762;1899.6580615489206;1946.3650533551743;1950.4051264364364;1960.2159136345433;80018;80183;81084.9999999994;81817;83585.58207208986;10198.317277649254;10316.638917637847;10719.678409647722;10750.301230419998;10850.891491085145;14191.858081419054;14221.862251019893;14307.427702891768;14352.138871667734;14352.56942291733;7437.144728356308;7471.775846724607;7549.8668052758585;7571.3942884569105;7653.196414376522;7081.468889833283;7134.429245660334;7144.821379465555;7356.705731770776;7382.1141463023705;6417.711202995783;6431.517636177306;6466.579672321284;6535.627518221227;6573.211214654272;1832.230463205457;1865.6082222733107;1870.551067743759;1905.1712505060343;1909.4080834941212;857.352698712468;1020.8519764634086;1050.5745173204864;1070.4487189097622;1082.6643740440488;18860.905695471436;19728.506787330432;19785.208591656174;19890;20189;955.2978560757838;965.1795973422675;1017.6336518853207;1017.97455063624;1083.539495714334;10907;11142.275752076252;11494.367809770543;11514;11514.251573647685;471039;477159;488718;489271;491383;45997.54002459999;47131.05737885223;52913.47086529162;66281.06940750711;77457.83813242806;946.6639343033262;949.1457688080644;970.6408628807386;974.965876194344;975.7170420578095;17091.401800936907;17404.39084632032;17858.821411785717;18084.186211620246;18187.727184091986;35272;37557.43663845099;37670.8116459416;38197;39908.60091399165;49705;50130.498695014045;50625.240621391444;50772;51021.7448912753;8387.748367548984;8450.873236901574;8486.872696909428;8669.6098675561;8710.738677839676;11651.00966417864;12510.124291299522;12936.288504132363;13187.934060329637;13222.801657974946;10766.461676915966;10856.620018299322;11147.721306967067;11283;11334.773304534028;10028.197744180594;10817.729556761144;10879.782404352027;10979.17656175774;11619.941900290445;6093.9085913712215;10345.431001295;10596.947015265183;11027.944860275647;11847.881521184678;7864.410169237216;8132.75601731949;8306.127856575215;8488.363372747192;8509.914900850912;9330.533473326172;10073.143782778547;10269.691909242734;10406.791864162826;10459.633912813015 09:50:02 INFO - PROCESS | 13052 | |3;mootools.html;1325.7812460943944;1413.17329362323;1441.4738620403552;1550.3953458151323;1705.1644694099887;7413.925860741398;8100.594970251486;8403.453775504604;8425.83148337033;8660.8700869487;8420.32637389009;8472.110428405022;8473.87289190661;8488.872666909985;8496.405251632388;2261.2424837679355;2280.429892526868;2283.862968221904;2285;2285.1773361589812;2137.3801597536694;2203.316971738758;2221.0338502751274;2221.9333419997424;2224.899879505418;2159.276642324823;2190.452386903274;2219.7447293561286;2223.288547664748;2225.1321984426086;2215.490437199437;2235.9888200558976;2239.8320125990535;2240;2243.607368710469;2213.7824196691754;2215.9113635454723;2219.755826859044;2222.5110475695483;2227.120287486434;8303.460275082161;8429.072801991775;8430.704925327645;8480.236778689887;8535.95732021336;4837.588804951583;5038.748062596856;5039.6220283478515;5050.318207042076;5061.114304996611;3105.7288620727486;3175.9364812703616;3240.6921342472665;3271.5583396241677;3278.098522906196;989.2432289298692;997.4214955325893;1000.0699349604864;1007.476112421544;1018.5263852308638;10899.945500272528;11291.322520648706;11343.149263805248;11614.4192790361;11616.36110013949;4060.939085913714;4162.5629308922735;4240.236757383701;4257.6168144867115;4270.487541494978;8798.736037918872;8816.515091669891;8835.337349698799;8835.469871807712;8852.424592401601;4795.784189711471;4801.423829140525;4804.183288840905;4806.7836947337455;4808.951910480921;3790.924181516354;3996.7202295839593;4101.2412703649325;4102.077032667626;4457.0194557197365;3308.354870800171;3599.5140656011104;3600.1719604491113;3612.169201083713;3668.192997540537;1037.8495118207968;1067.7597540553313;1068.7114479090758;1087.287826473661;1100.983485247722;771.9112302085219;907.2923119966437;911.1344222988188;928.9038934057877;937.1659223291276;5511.779528818883;5747.770089196469;5760.8271751847515;5877.23595932526;6121.938780612226;936.6066252174103;953.7377221264207;981.1954197558076;983.6458874805064;1017.531935309751;3972.2651309507833;4142.399352093929;4185.937210941838;4243.702940794176;4292.527821939584;10137.34107283039;10356.275060745826;10452.634157804592;10455.738606534745;10471.3193642413;10404.167666586654;10421.478926053667;10428.5828566858;10436.165106791439;10440.477976101316;7374.967504549368;7410.481266311306;7477.327040566375;7528.360089392353;7622.733204337822;5800.4489573490455;5820.82537523875;5894.2926848778425;6024.638521688713;6025.8192254232445;9420.62317507292;9459.574319155518;9508.144267016001;9535.570899309687;9609.087136722279;9228.261739060994;9562.330636855353;9565;9602.711918642452;9616.374935629161;6598.307177746459;6731.158605174353;6998.75504357345;7033.788986330418;7094.184168820651;8194.426390152632;8282.50304981703;8293.419460637697;8298.170182981654;8299.211574900375;7108.218096009538;7434.59109748969;7464.402847772274;7473.327400533978;7536.773896783106;7063.117110361205;7347.8163045924275;7365.1530074042175;7368.778936631909;7546.358559522502;6828.385445309946;6947.201071876597;6949.722011119701;6950.165980082423;6955.791326260221;4839.491853355487;5032.672876263031;5071.239314102914;5123.974380128225;5168.250603662523;5068.442471328225;5139.229115632685;5152.742362881766;5322.8935421292135;5449.782008719607 09:50:02 INFO - PROCESS | 13052 | |4;prototype.html;315.4589878358615;319.6052874699746;321.4118163760319;326.1389930583259;329.2098962490024;5015.297858299832;5141.562967147797;5162.870928226796;5205.479452054793;5269.6574722643045;4984.950150498494;5099.617528685345;5124.846254612358;5128.358955130609;5143.717095559743;4074.4295798588073;4075.551689314173;4076.9592304077023;4082.2447847148223;4088.2641124597562;3989.6608788253034;4075.3683179107284;4075.857344992926;4092.283850326196;4102.138550904298;4036.717429779902;4045.393191021341;4047.817848196838;4056.1684854604882;4058.127502586943;4055.371417430288;4057.4319595256393;4060.330045542497;4072.75563466193;4080.12277360366;3585.1395665040204;3638.43604241343;3642.1805093853936;3644.526211592476;3655.4882316475714;4915.680480768739;5034.144195486776;5035.194368900995;5048.596112311006;5158.2520534522655;4605.240135377659;4610.654200934943;4619.86140415788;4622.1911165546235;4623.630109591225;5345.30511033567;5355.196720491918;5362;5369.919451208235;5381.246625472437;3677.485152078711;3887.1836914248042;3890.2997460457104;3894.9026274343087;3899.200663863901;5577.69322687252;5605.215269862222;5605.775768969236;5607.691576963306;5608.355039170507;3947.2302900934615;3959.604039596017;3964.682825373963;3972.7219094663674;4003.159336539301;3949.2891279569953;4028.8589899353383;4041.413994970713;4068.4100805383646;4071.185762847383;4576.695641742088;4583.793729282191;4588.242939914877;4602.7008244464005;4617.607503362185;5314.73426328682;5397.433269506724;5413.7834486620095;5416.729163541807;5497.45025497455;1304.8173255744202;1313.7044165062784;1337.7993301004926;1360.197483484729;1376.1880490510646;928.3223247029571;953.6566835939057;961.5240455974198;962.2157941277836;962.3648392061345;5190.792368305301;5192.9740351298005;5200.323957885526;5220.7128607926925;5239.345081864767;5498.862528436741;5501.944980550224;5503.257060296809;5504.504594586507;5539.612227144061;4949.65352425334;5122.1804511278015;5181.611379146579;5193.417076642624;5202.713850738246;2584.612308153792;2602.4014476670473;2610.9347266318496;2615.398458354589;2621.921342359732;5065.924011139836;5115.309433226541;5118.181091025493;5134.5378915897;5182.663126896815;4797.592204662573;4951.653384263139;4983.9252411213865;4995.475475075064;5012.398512178562;5011.574016208663;5038.974805126024;5055.949440505621;5057.848264552069;5080.263361812516;4828.2998965150555;4866.270059490963;4887.706737595756;4914.533119353656;4922.022176583116;4509.661775366926;4807.735574543433;4818.6626936114135;4822.9758851206925;4826.493218212177;4996.325496057985;4997.900041999213;5010.649254552146;5011.699298042129;5023.397192336943;3613.512175856331;3618.692411145082;3621.9858439636946;3673.522442082512;3674.805688151308;4602.734248081804;4897.608191344755;4912.557869791736;4974.154393752999;4994.850154495371;4753.786079626356;4774.761261936958;4776.307435421914;4781.545753153445;4789.44921334051;4709.858704238878;4774.806298425394;4782.450018247946;4802.543758342952;4838.371011768448;4515.503294637653;4692.648051396227;4759.214729569582;4774.594159496482;4804.711717296834;4777.641676874179;4811.566958973709;4812.975935120302;4820.831270905642;4859.562639362333;4698.506656800987;4767.69010014348;4781.019890922283;4783.808647654055;4788.545088216614 09:50:02 INFO - PROCESS | 13052 | |5;yui.html;668.558751224192;743.8884167374894;747.2303527366814;782.1201148707704;806.7499075286665;14849.480268190615;15204.847951520482;15293.311800968955;15315;15340.769888451681;14899.32953017114;14970.17664028478;14996.250187490636;15021.173835439047;15225.771613425808;794.8966634337543;799.013218674936;799.7840583042575;799.808046068945;800.5676934455416;661.6294874870088;669.7990602819159;777.7239080126559;786.6066966516742;789.7591234673422;839.911809260028;842.321930845669;842.7850898020996;843.1779015459939;843.4306842881061;825.1871906172415;831.6590198018783;833.1834801894117;833.7540425574471;834.1033389106734;501.4208589079619;508.5499333090205;585.5783835638333;593.4036293525023;605.6880706436189;14006.089604175799;14086.15483071019;14100.365483553265;14103;14157.787633185511;13742.175469471864;14624.415023399051;14706.264686765726;14796.18620975846;14850.108993460426;13140.868591314156;13240.933795331059;13583.592492225249;13669.794953075712;13831.930840345834;4270.487541495009;4317.503487098961;4321.719088259285;4388.034632380871;4425.645948324126;77575;78604;78694.60652696699;78987.99999999943;80451.59774201091;11339.546418143182;11462.885371146182;11587.88412115885;11748.88251117495;11831.585894493652;21906.671399929015;21980.78019219787;21994.780052199592;22028.88985555062;22043.559128817335;7913.841723165505;7937.404694647925;8574.828503429897;8578.656853725906;8579.785505362415;4541.75020373876;4639.327297541838;4676.205045142266;4781.737004464788;4808.831690890802;3317.452620317669;3415.846286917095;3424.0412684448393;3445.13871532117;3453.6546345365264;601.0773462734682;610.915624766035;619.9070139479114;634.3181080338654;640.6764583885108;622.0420552349422;630.5271046714964;702.5621094801032;736.3262614707589;736.8121129112042;9188.95405522968;9868.506574671237;9999.400035997864;10004.49977501122;10179.338343007681;612.2010775937356;659.7921654678761;711.3384552366349;719.4424321151046;743.2975837833304;4360.324149756792;4703.388559487314;4717.669763116617;4720.858374248778;4750.857474275777;116966.41516792361;118250;120123;123305.38347308207;125019.3749031249;64890;76968.23031769722;77123;90215;102296.48851755694;86175;90670.54664726766;90896;92593;94741.5262923681;9082.36423450352;9130.49782261982;9274.350795444525;9344.95327523358;9351.39215950961;43199.784001081054;44178.337324940796;45682.771586141855;53154;53321.20018199652;64861;70029.64985175042;71395.64302178456;74524;75176.24823751912;13061.477540898257;13403.195808251536;13446.590829924551;13449.46202151903;13586;15248.237588120328;15408.306626202071;15419.61450963735;15528.767068493755;16103.275352608927;13749.793753093913;13960.650983725489;14041.946853985788;14452.783208251674;14712.337944792296;14192.503262385768;14543.127412355292;14658.340374683377;14674.63313417173;14719.264036797904;13527.120737152054;14217.928910355382;14334.21161836109;14408.014843777071;15071.397144114113;10776.892231077902;10917.344959302467;10982.615608453665;11364.943175284401;11441.713957151138;10632.627858024933;11096.112311014938;11251.324920504796;11334.659960201207;11438.485268162785 09:50:02 INFO - PROCESS | 13052 | __end_tp_report 09:50:02 INFO - PROCESS | 13052 | __start_cc_report 09:50:02 INFO - PROCESS | 13052 | _x_x_mozilla_cycle_collect,1063 09:50:02 INFO - PROCESS | 13052 | __end_cc_report 09:50:02 INFO - PROCESS | 13052 | __startTimestamp1475167802804__endTimestamp 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | ------- Summary: start ------- 09:50:02 INFO - PROCESS | 13052 | Number of tests: 6 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#0] dojo.html Cycles:165 Average:5263.46 Median:5466.00 stddev:600.80 (11.0%) stddev-sans-first:574.97 09:50:02 INFO - PROCESS | 13052 | Values: 2965.7 2987.0 2991.6 3016.0 3027.0 5199.3 5255.7 5301.0 5328.2 5367.2 5180.1 5252.4 5275.7 5347.5 5384.9 4837.6 4847.0 4902.5 4905.7 4923.6 4841.5 4945.1 4952.3 4958.9 5012.6 4895.6 4902.2 4929.1 4950.6 4996.6 4788.0 4805.5 4828.3 4855.0 4855.5 4713.6 4715.5 4721.8 4722.1 4788.2 5213.5 5254.4 5264.9 5283.3 5288.7 5482.7 5529.9 5564.5 5587.4 5606.1 5630.4 5666.2 5672.4 5678.3 5679.1 5273.9 5322.6 5323.3 5324.2 5378.5 5592.8 5741.0 5751.5 5815.7 5842.4 5594.9 5597.8 5602.5 5653.4 5671.2 5671.2 5682.1 5682.4 5684.5 5685.7 5441.1 5459.8 5466.0 5622.7 5625.3 5503.4 5538.0 5539.3 5597.3 5601.7 4265.4 4305.9 4306.0 4307.9 4353.5 3823.4 3838.7 3862.7 3871.9 3872.3 5331.8 5360.2 5380.8 5415.6 5480.9 5712.1 5713.4 5717.6 5718.4 5725.6 5283.7 5404.0 5414.8 5417.7 5476.8 4819.3 4832.3 4855.0 4860.6 4899.7 5866.5 5868.7 5881.4 5935.1 5941.5 5597.8 5651.0 5651.9 5653.1 5663.3 5739.0 5739.5 5856.4 5858.0 5861.1 5522.4 5563.2 5583.4 5606.0 5660.8 5694.7 5701.7 5708.8 5713.4 5714.9 5349.0 5551.6 5561.7 5562.7 5565.3 5443.9 5535.3 5600.4 5604.4 5671.5 5541.7 5587.1 5589.9 5590.4 5593.1 5602.3 5611.4 5616.4 5630.2 5633.1 5343.8 5384.9 5389.2 5443.6 5445.2 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#1] ext.html Cycles:180 Average:61243.02 Median:39818.20 stddev:121134.81 (304.2%) stddev-sans-first:121388.93 09:50:02 INFO - PROCESS | 13052 | Values: 556.1 591.2 598.3 605.8 617.4 31835.7 42838.1 43011.6 43113.4 43138.4 44051.3 44346.8 44446.0 44487.6 44523.8 8127.6 8800.9 8948.4 8954.0 8959.1 8076.9 10439.7 10626.9 10658.0 11072.2 10885.0 10942.2 10945.1 10954.4 10954.7 10824.8 10892.8 10893.9 10902.4 10914.0 10242.2 10256.7 10316.5 10319.0 10324.8 42126.8 42255.6 42422.6 42574.4 42594.6 3359.3 3494.4 3701.0 4059.0 4176.3 31932.8 32860.3 32905.0 33345.7 33526.3 4102.6 4193.8 4232.5 4352.1 4440.3 367348.0 368040.0 369808.0 370339.0 372040.0 56552.7 56881.4 56886.7 57009.1 57292.0 59039.7 59176.7 59366.1 59386.7 59499.7 13859.7 13950.6 13952.2 14110.3 14125.9 10295.7 10321.7 10660.6 10964.6 10979.6 15566.0 16131.3 16155.8 16173.6 16206.0 6208.6 6497.2 6575.8 6734.6 6735.2 901.9 1003.6 1065.4 1082.4 1141.7 31351.5 31352.2 31434.0 32096.5 32347.2 1078.4 1087.8 1113.1 1137.5 1141.4 16084.4 16728.9 16855.2 17022.4 17047.5 501686.0 521747.0 523239.8 523251.0 524717.0 47823.8 53918.7 55482.7 55487.4 60931.4 1295.7 1301.2 1324.9 1333.1 1333.2 30167.2 33074.8 33115.7 33219.3 33356.8 50395.0 51078.0 51084.2 51136.2 51232.0 469196.0 469882.0 470489.0 471028.0 471102.0 38605.2 39945.6 39955.6 40000.0 40284.4 46460.0 46883.0 47010.1 47181.3 47457.0 39582.6 39917.0 40647.4 40654.0 41855.0 38957.4 39719.4 40463.6 40843.0 42074.6 40178.2 41011.0 41116.2 41138.6 41479.0 34500.3 35494.6 35658.3 35884.3 36586.3 27534.4 27782.7 27866.2 27951.0 28043.6 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#2] jquery.html Cycles:180 Average:27356.49 Median:17823.57 stddev:79191.40 (444.3%) stddev-sans-first:79387.64 09:50:02 INFO - PROCESS | 13052 | Values: 375.1 382.8 403.1 403.6 413.7 13171.7 14847.0 15813.7 18776.1 18883.9 17294.1 17405.7 17681.6 17790.9 18184.2 5341.2 5619.6 5624.0 5624.7 5627.9 5421.6 5541.5 5556.8 5603.9 5611.9 5596.2 5603.4 5607.3 5609.1 5617.7 5431.2 5491.7 5492.3 5510.3 5511.1 4970.3 4972.7 4973.6 4975.0 4976.1 22430.0 22529.4 22639.5 22669.8 22682.3 2119.4 2155.6 2181.3 2237.1 2468.8 13963.3 14219.0 14272.6 14346.6 14931.6 1803.2 1899.7 1946.4 1950.4 1960.2 80018.0 80183.0 81085.0 81817.0 83585.6 10198.3 10316.6 10719.7 10750.3 10850.9 14191.9 14221.9 14307.4 14352.1 14352.6 7437.1 7471.8 7549.9 7571.4 7653.2 7081.5 7134.4 7144.8 7356.7 7382.1 6417.7 6431.5 6466.6 6535.6 6573.2 1832.2 1865.6 1870.6 1905.2 1909.4 857.4 1020.9 1050.6 1070.4 1082.7 18860.9 19728.5 19785.2 19890.0 20189.0 955.3 965.2 1017.6 1018.0 1083.5 10907.0 11142.3 11494.4 11514.0 11514.3 471039.0 477159.0 488718.0 489271.0 491383.0 45997.5 47131.1 52913.5 66281.1 77457.8 946.7 949.1 970.6 975.0 975.7 17091.4 17404.4 17858.8 18084.2 18187.7 35272.0 37557.4 37670.8 38197.0 39908.6 49705.0 50130.5 50625.2 50772.0 51021.7 8387.7 8450.9 8486.9 8669.6 8710.7 11651.0 12510.1 12936.3 13187.9 13222.8 10766.5 10856.6 11147.7 11283.0 11334.8 10028.2 10817.7 10879.8 10979.2 11619.9 6093.9 10345.4 10596.9 11027.9 11847.9 7864.4 8132.8 8306.1 8488.4 8509.9 9330.5 10073.1 10269.7 10406.8 10459.6 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#3] mootools.html Cycles:180 Average:5472.79 Median:5064.78 stddev:3083.37 (60.9%) stddev-sans-first:3076.27 09:50:02 INFO - PROCESS | 13052 | Values: 1325.8 1413.2 1441.5 1550.4 1705.2 7413.9 8100.6 8403.5 8425.8 8660.9 8420.3 8472.1 8473.9 8488.9 8496.4 2261.2 2280.4 2283.9 2285.0 2285.2 2137.4 2203.3 2221.0 2221.9 2224.9 2159.3 2190.5 2219.7 2223.3 2225.1 2215.5 2236.0 2239.8 2240.0 2243.6 2213.8 2215.9 2219.8 2222.5 2227.1 8303.5 8429.1 8430.7 8480.2 8536.0 4837.6 5038.7 5039.6 5050.3 5061.1 3105.7 3175.9 3240.7 3271.6 3278.1 989.2 997.4 1000.1 1007.5 1018.5 10899.9 11291.3 11343.1 11614.4 11616.4 4060.9 4162.6 4240.2 4257.6 4270.5 8798.7 8816.5 8835.3 8835.5 8852.4 4795.8 4801.4 4804.2 4806.8 4809.0 3790.9 3996.7 4101.2 4102.1 4457.0 3308.4 3599.5 3600.2 3612.2 3668.2 1037.8 1067.8 1068.7 1087.3 1101.0 771.9 907.3 911.1 928.9 937.2 5511.8 5747.8 5760.8 5877.2 6121.9 936.6 953.7 981.2 983.6 1017.5 3972.3 4142.4 4185.9 4243.7 4292.5 10137.3 10356.3 10452.6 10455.7 10471.3 10404.2 10421.5 10428.6 10436.2 10440.5 7375.0 7410.5 7477.3 7528.4 7622.7 5800.4 5820.8 5894.3 6024.6 6025.8 9420.6 9459.6 9508.1 9535.6 9609.1 9228.3 9562.3 9565.0 9602.7 9616.4 6598.3 6731.2 6998.8 7033.8 7094.2 8194.4 8282.5 8293.4 8298.2 8299.2 7108.2 7434.6 7464.4 7473.3 7536.8 7063.1 7347.8 7365.2 7368.8 7546.4 6828.4 6947.2 6949.7 6950.2 6955.8 4839.5 5032.7 5071.2 5124.0 5168.3 5068.4 5139.2 5152.7 5322.9 5449.8 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#4] prototype.html Cycles:180 Average:4328.42 Median:4800.07 stddev:1222.40 (25.5%) stddev-sans-first:1188.15 09:50:02 INFO - PROCESS | 13052 | Values: 315.5 319.6 321.4 326.1 329.2 5015.3 5141.6 5162.9 5205.5 5269.7 4985.0 5099.6 5124.8 5128.4 5143.7 4074.4 4075.6 4077.0 4082.2 4088.3 3989.7 4075.4 4075.9 4092.3 4102.1 4036.7 4045.4 4047.8 4056.2 4058.1 4055.4 4057.4 4060.3 4072.8 4080.1 3585.1 3638.4 3642.2 3644.5 3655.5 4915.7 5034.1 5035.2 5048.6 5158.3 4605.2 4610.7 4619.9 4622.2 4623.6 5345.3 5355.2 5362.0 5369.9 5381.2 3677.5 3887.2 3890.3 3894.9 3899.2 5577.7 5605.2 5605.8 5607.7 5608.4 3947.2 3959.6 3964.7 3972.7 4003.2 3949.3 4028.9 4041.4 4068.4 4071.2 4576.7 4583.8 4588.2 4602.7 4617.6 5314.7 5397.4 5413.8 5416.7 5497.5 1304.8 1313.7 1337.8 1360.2 1376.2 928.3 953.7 961.5 962.2 962.4 5190.8 5193.0 5200.3 5220.7 5239.3 5498.9 5501.9 5503.3 5504.5 5539.6 4949.7 5122.2 5181.6 5193.4 5202.7 2584.6 2602.4 2610.9 2615.4 2621.9 5065.9 5115.3 5118.2 5134.5 5182.7 4797.6 4951.7 4983.9 4995.5 5012.4 5011.6 5039.0 5055.9 5057.8 5080.3 4828.3 4866.3 4887.7 4914.5 4922.0 4509.7 4807.7 4818.7 4823.0 4826.5 4996.3 4997.9 5010.6 5011.7 5023.4 3613.5 3618.7 3622.0 3673.5 3674.8 4602.7 4897.6 4912.6 4974.2 4994.9 4753.8 4774.8 4776.3 4781.5 4789.4 4709.9 4774.8 4782.5 4802.5 4838.4 4515.5 4692.6 4759.2 4774.6 4804.7 4777.6 4811.6 4813.0 4820.8 4859.6 4698.5 4767.7 4781.0 4783.8 4788.5 09:50:02 INFO - PROCESS | 13052 | 09:50:02 INFO - PROCESS | 13052 | [#5] yui.html Cycles:180 Average:20644.27 Median:4483.70 stddev:29603.63 (660.3%) stddev-sans-first:29648.67 09:50:02 INFO - PROCESS | 13052 | Values: 668.6 743.9 747.2 782.1 806.7 14849.5 15204.8 15293.3 15315.0 15340.8 14899.3 14970.2 14996.3 15021.2 15225.8 794.9 799.0 799.8 799.8 800.6 661.6 669.8 777.7 786.6 789.8 839.9 842.3 842.8 843.2 843.4 825.2 831.7 833.2 833.8 834.1 501.4 508.5 585.6 593.4 605.7 14006.1 14086.2 14100.4 14103.0 14157.8 13742.2 14624.4 14706.3 14796.2 14850.1 13140.9 13240.9 13583.6 13669.8 13831.9 4270.5 4317.5 4321.7 4388.0 4425.6 77575.0 78604.0 78694.6 78988.0 80451.6 11339.5 11462.9 11587.9 11748.9 11831.6 21906.7 21980.8 21994.8 22028.9 22043.6 7913.8 7937.4 8574.8 8578.7 8579.8 4541.8 4639.3 4676.2 4781.7 4808.8 3317.5 3415.8 3424.0 3445.1 3453.7 601.1 610.9 619.9 634.3 640.7 622.0 630.5 702.6 736.3 736.8 9189.0 9868.5 9999.4 10004.5 10179.3 612.2 659.8 711.3 719.4 743.3 4360.3 4703.4 4717.7 4720.9 4750.9 116966.4 118250.0 120123.0 123305.4 125019.4 64890.0 76968.2 77123.0 90215.0 102296.5 86175.0 90670.5 90896.0 92593.0 94741.5 9082.4 9130.5 9274.4 9345.0 9351.4 43199.8 44178.3 45682.8 53154.0 53321.2 64861.0 70029.6 71395.6 74524.0 75176.2 13061.5 13403.2 13446.6 13449.5 13586.0 15248.2 15408.3 15419.6 15528.8 16103.3 13749.8 13960.7 14041.9 14452.8 14712.3 14192.5 14543.1 14658.3 14674.6 14719.3 13527.1 14217.9 14334.2 14408.0 15071.4 10776.9 10917.3 10982.6 11364.9 11441.7 10632.6 11096.1 11251.3 11334.7 11438.5 09:50:02 INFO - PROCESS | 13052 | -------- Summary: end -------- 09:50:02 INFO - PROCESS | 13052 | 09:50:03 INFO - TEST-INFO | 13052: exit 0 09:50:03 INFO - TEST-OK | dromaeo_css | took 1115472ms 09:50:03 INFO - TEST-START | kraken 09:50:03 INFO - operating with platform_type : linux_ 09:50:03 INFO - Initialising browser for kraken test... 09:50:03 INFO - TEST-INFO | started process 13358 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl5wXTH/profile http://localhost:37791/getInfo.html) 09:50:07 INFO - PROCESS | 13358 | __metrics Screen width/height:1600/1200 09:50:07 INFO - PROCESS | 13358 | colorDepth:24 09:50:07 INFO - PROCESS | 13358 | Browser inner width/height: 1024/697 09:50:07 INFO - PROCESS | 13358 | __metrics 09:50:08 INFO - PROCESS | 13358 | 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:50:08 INFO - PROCESS | 13358 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:50:08 INFO - PROCESS | 13358 | console.error: PushService: 09:50:08 INFO - PROCESS | 13358 | stateChangeProcessEnqueue: Error transitioning state 09:50:08 INFO - PROCESS | 13358 | UnknownError 09:50:09 INFO - TEST-INFO | 13358: exit 0 09:50:09 INFO - Browser initialized. 09:50:09 INFO - Running cycle 1/1 for kraken test... 09:50:09 INFO - TEST-INFO | started process 13440 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl5wXTH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:21 INFO - PROCESS | 13440 | RSS: Main: 173821952 09:50:21 INFO - PROCESS | 13440 | 09:50:24 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37791/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:25 INFO - PROCESS | 13440 | RSS: Main: 232321024 09:50:25 INFO - PROCESS | 13440 | 09:50:27 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37791/tests/kraken/driver.html?testName=audio-dft) 09:50:28 INFO - PROCESS | 13440 | RSS: Main: 216457216 09:50:28 INFO - PROCESS | 13440 | 09:50:31 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37791/tests/kraken/driver.html?testName=audio-fft) 09:50:32 INFO - PROCESS | 13440 | RSS: Main: 298651648 09:50:32 INFO - PROCESS | 13440 | 09:50:34 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37791/tests/kraken/driver.html?testName=audio-oscillator) 09:50:34 INFO - PROCESS | 13440 | RSS: Main: 207560704 09:50:34 INFO - PROCESS | 13440 | 09:50:36 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:37 INFO - PROCESS | 13440 | RSS: Main: 212463616 09:50:37 INFO - PROCESS | 13440 | 09:50:40 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37791/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:41 INFO - PROCESS | 13440 | RSS: Main: 249290752 09:50:41 INFO - PROCESS | 13440 | 09:50:44 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37791/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:44 INFO - PROCESS | 13440 | RSS: Main: 248295424 09:50:44 INFO - PROCESS | 13440 | 09:50:47 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37791/tests/kraken/driver.html?testName=json-parse-financial) 09:50:48 INFO - PROCESS | 13440 | RSS: Main: 248221696 09:50:48 INFO - PROCESS | 13440 | 09:50:49 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:50 INFO - PROCESS | 13440 | RSS: Main: 210771968 09:50:50 INFO - PROCESS | 13440 | 09:50:52 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:52 INFO - PROCESS | 13440 | RSS: Main: 217804800 09:50:52 INFO - PROCESS | 13440 | 09:50:54 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:55 INFO - PROCESS | 13440 | RSS: Main: 222629888 09:50:55 INFO - PROCESS | 13440 | 09:50:58 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:58 INFO - PROCESS | 13440 | RSS: Main: 217497600 09:50:58 INFO - PROCESS | 13440 | 09:51:01 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:02 INFO - PROCESS | 13440 | RSS: Main: 221220864 09:51:02 INFO - PROCESS | 13440 | 09:51:03 INFO - PROCESS | 13440 | Cycle 1(1): loaded http://localhost:37791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37791/tests/kraken/driver.html?testName=ai-astar) 09:51:03 INFO - PROCESS | 13440 | RSS: Main: 272510976 09:51:03 INFO - PROCESS | 13440 | 09:51:03 INFO - PROCESS | 13440 | __start_tp_report 09:51:03 INFO - PROCESS | 13440 | _x_x_mozilla_page_load 09:51:03 INFO - PROCESS | 13440 | _x_x_mozilla_page_load_details 09:51:03 INFO - PROCESS | 13440 | |i|pagename|runs| 09:51:03 INFO - PROCESS | 13440 | |0;ai-astar;108;111;112;111;110;110;110;111;111;111 09:51:03 INFO - PROCESS | 13440 | |1;audio-beat-detection;148;119;118;179;122;119;118;117;146;121 09:51:03 INFO - PROCESS | 13440 | |2;audio-dft;171;161;161;161;161;161;162;162;162;163 09:51:03 INFO - PROCESS | 13440 | |3;audio-fft;74;75;75;109;75;76;75;76;104;76 09:51:03 INFO - PROCESS | 13440 | |4;audio-oscillator;82;82;115;83;82;82;104;82;82;119 09:51:03 INFO - PROCESS | 13440 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;117 09:51:03 INFO - PROCESS | 13440 | |6;imaging-darkroom;145;145;145;146;145;145;146;145;145;145 09:51:03 INFO - PROCESS | 13440 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:51:03 INFO - PROCESS | 13440 | |8;json-parse-financial;52;51;51;51;52;53;53;53;53;53 09:51:03 INFO - PROCESS | 13440 | |9;json-stringify-tinderbox;59;61;61;60;61;60;59;59;60;60 09:51:03 INFO - PROCESS | 13440 | |10;stanford-crypto-aes;88;81;84;79;79;79;79;82;79;84 09:51:03 INFO - PROCESS | 13440 | |11;stanford-crypto-ccm;132;122;131;123;122;133;133;128;158;122 09:51:03 INFO - PROCESS | 13440 | |12;stanford-crypto-pbkdf2;165;162;164;166;166;165;165;165;165;164 09:51:03 INFO - PROCESS | 13440 | |13;stanford-crypto-sha256-iterative;66;66;64;64;69;63;64;65;65;64 09:51:03 INFO - PROCESS | 13440 | __end_tp_report 09:51:03 INFO - PROCESS | 13440 | __start_cc_report 09:51:03 INFO - PROCESS | 13440 | _x_x_mozilla_cycle_collect,1586 09:51:03 INFO - PROCESS | 13440 | __end_cc_report 09:51:03 INFO - PROCESS | 13440 | __startTimestamp1475167863988__endTimestamp 09:51:03 INFO - PROCESS | 13440 | 09:51:03 INFO - PROCESS | 13440 | ------- Summary: start ------- 09:51:03 INFO - PROCESS | 13440 | Number of tests: 14 09:51:03 INFO - PROCESS | 13440 | 09:51:03 INFO - PROCESS | 13440 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.08 (1.0%) stddev-sans-first:0.67 09:51:03 INFO - PROCESS | 13440 | Values: 108.0 111.0 112.0 111.0 110.0 110.0 110.0 111.0 111.0 111.0 09:51:03 INFO - PROCESS | 13440 | 09:51:03 INFO - PROCESS | 13440 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:121.50 stddev:20.60 (17.0%) stddev-sans-first:20.88 09:51:03 INFO - PROCESS | 13440 | Values: 148.0 119.0 118.0 179.0 122.0 119.0 118.0 117.0 146.0 121.0 09:51:03 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:3.06 (1.9%) stddev-sans-first:0.73 09:51:04 INFO - PROCESS | 13440 | Values: 171.0 161.0 161.0 161.0 161.0 161.0 162.0 162.0 162.0 163.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:13.24 (17.7%) stddev-sans-first:13.77 09:51:04 INFO - PROCESS | 13440 | Values: 74.0 75.0 75.0 109.0 75.0 76.0 75.0 76.0 104.0 76.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:82.00 stddev:15.20 (18.5%) stddev-sans-first:15.74 09:51:04 INFO - PROCESS | 13440 | Values: 82.0 82.0 115.0 83.0 82.0 82.0 104.0 82.0 82.0 119.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:51:04 INFO - PROCESS | 13440 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:51:04 INFO - PROCESS | 13440 | Values: 145.0 145.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:51:04 INFO - PROCESS | 13440 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:53.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 09:51:04 INFO - PROCESS | 13440 | Values: 52.0 51.0 51.0 51.0 52.0 53.0 53.0 53.0 53.0 53.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.78 09:51:04 INFO - PROCESS | 13440 | Values: 59.0 61.0 61.0 60.0 61.0 60.0 59.0 59.0 60.0 60.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:3.10 (3.8%) stddev-sans-first:2.18 09:51:04 INFO - PROCESS | 13440 | Values: 88.0 81.0 84.0 79.0 79.0 79.0 79.0 82.0 79.0 84.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:131.50 stddev:10.80 (8.2%) stddev-sans-first:11.44 09:51:04 INFO - PROCESS | 13440 | Values: 132.0 122.0 131.0 123.0 122.0 133.0 133.0 128.0 158.0 122.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 09:51:04 INFO - PROCESS | 13440 | Values: 165.0 162.0 164.0 166.0 166.0 165.0 165.0 165.0 165.0 164.0 09:51:04 INFO - PROCESS | 13440 | 09:51:04 INFO - PROCESS | 13440 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:1.70 (2.6%) stddev-sans-first:1.76 09:51:04 INFO - PROCESS | 13440 | Values: 66.0 66.0 64.0 64.0 69.0 63.0 64.0 65.0 65.0 64.0 09:51:04 INFO - PROCESS | 13440 | -------- Summary: end -------- 09:51:04 INFO - PROCESS | 13440 | 09:51:05 INFO - TEST-INFO | 13440: exit 0 09:51:05 INFO - TEST-OK | kraken | took 61449ms 09:51:05 INFO - SUITE-END | took 1176s 09:51:05 INFO - Completed test suite (00:19:37) 09:51:05 INFO - javascript benchmark 09:51:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2965.6886026967168, 2986.970130298699, 2991.6110905582273, 3016.0, 3026.954595681064, 5199.272101905736, 5255.737213139342, 5301.0, 5328.200769884519, 5367.168088946215, 5180.067587834188, 5252.448492908247, 5275.709835959021, 5347.491988261118, 5384.946150538493, 4837.637177211705, 4846.9515304846855, 4902.485239049902, 4905.705657660552, 4923.55687988081, 4841.491643377448, 4945.060438516688, 4952.257161425779, 4958.900821983558, 5012.649114561982, 4895.608351331921, 4902.166631672589, 4929.137400954836, 4950.55445009949, 4996.5503104720565, 4787.970586323922, 4805.519448055166, 4828.275758636198, 4855.028994201139, 4855.4901735317635, 4713.5522125398375, 4715.528447155257, 4721.811127554894, 4722.1028004679, 4788.233882578771, 5213.504717051874, 5254.395744489394, 5264.894702105975, 5283.260343551906, 5288.735563221862, 5482.698451585161, 5529.861753456155, 5564.4713752193475, 5587.441255874421, 5606.103023516218, 5630.436956304377, 5666.150077488369, 5672.432756724335, 5678.318601767773, 5679.062954612484, 5273.947260527384, 5322.600804939645, 5323.254744335834, 5324.227986941872, 5378.515933565998, 5592.804251851166, 5741.0, 5751.539876809845, 5815.680137592389, 5842.357340692521, 5594.916076258859, 5597.832065038056, 5602.52378547828, 5653.434656534314, 5671.177679236487, 5671.177679236487, 5682.119271512922, 5682.374938756733, 5684.54523638108, 5685.744141513642, 5441.075017247077, 5459.836204913859, 5466.0, 5622.690752008679, 5625.324961004706, 5503.394626591072, 5538.030844602178, 5539.279893613804, 5597.272354593958, 5601.691906945157, 4265.402843601898, 4305.913881722348, 4306.0, 4307.892302692395, 4353.499347575006, 3823.4264860271182, 3838.692904567684, 3862.748921320105, 3871.8838434847, 3872.341701910682, 5331.786728530816, 5360.249565060894, 5380.838574842761, 5415.620906536584, 5480.862978425571, 5712.11462223356, 5713.4000929902595, 5717.599768016282, 5718.428157184248, 5725.570582206268, 5283.709395983258, 5404.0, 5414.8104816332025, 5417.729113544307, 5476.808311709071, 4819.277108433763, 4832.251001094836, 4855.0, 4860.6111511079025, 4899.706017638953, 5866.472017518358, 5868.677222752619, 5881.441263080001, 5935.139404786281, 5941.524678025834, 5597.80407685729, 5651.0, 5651.915221271602, 5653.123765816305, 5663.348714897839, 5739.0, 5739.483446489836, 5856.355800861987, 5858.004139296245, 5861.0915308127305, 5522.447755224445, 5563.165525171256, 5583.385827559046, 5606.0, 5660.773569057192, 5694.686792226467, 5701.6864072474755, 5708.80019199326, 5713.371529131875, 5714.942850571441, 5349.0, 5551.555875530029, 5561.721913904369, 5562.694051827189, 5565.332160140809, 5443.918341224806, 5535.308086489189, 5600.35595906476, 5604.411536788579, 5671.546276297969, 5541.667499950016, 5587.050201465842, 5589.888202236014, 5590.385057643737, 5593.105103183634, 5602.327720673546, 5611.354694210218, 5616.354119276336, 5630.18362337467, 5633.0705433603, 5343.812966546152, 5384.9192262116885, 5389.245505629294, 5443.59173062014, 5445.210444485607], "value": 5221.985130347236, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.0519314568661, 591.1516973143539, 598.2671227746009, 605.772835186805, 617.4042049422308, 31835.68164318356, 42838.14323713528, 43011.56988430115, 43113.35329970045, 43138.352924706116, 44051.3392299115, 44346.778266108624, 44446.0, 44487.55512444874, 44523.77738111305, 8127.642983534768, 8800.8679869802, 8948.373613847034, 8954.0, 8959.14888085634, 8076.8788468173025, 10439.739006524822, 10626.946865265663, 10658.0, 11072.224944253905, 10885.0, 10942.23404361695, 10945.124390048817, 10954.39750813705, 10954.671359859218, 10824.78350432991, 10892.782144357148, 10893.945530272338, 10902.400367979759, 10914.0, 10242.231832612584, 10256.692299231036, 10316.535755891004, 10319.0, 10324.845127323097, 42126.78936605328, 42255.57744422578, 42422.57577424217, 42574.36138457926, 42594.57405425937, 3359.2777552826014, 3494.3884820156627, 3701.0, 4058.9594104059174, 4176.310908700061, 31932.840335798406, 32860.34279314424, 32905.01284961455, 33345.6665433345, 33526.32947341064, 4102.569230230843, 4193.790310484464, 4232.534421213664, 4352.107817897323, 4440.2895536714295, 367348.0, 368040.0, 369808.0, 370339.0, 372040.0, 56552.717236413555, 56881.431185687616, 56886.71556642273, 57009.14486282709, 57292.0, 59039.70480147572, 59176.704116479144, 59366.10950835741, 59386.70306648439, 59499.70250148721, 13859.653508662364, 13950.581482555539, 13952.232627205602, 14110.294485275901, 14125.929370353082, 10295.742606434898, 10321.74195645115, 10660.62687805923, 10964.56141754321, 10979.560817567208, 15566.0, 16131.274092665859, 16155.757663635055, 16173.595660108356, 16206.0, 6208.627482351073, 6497.180789378982, 6575.80272591823, 6734.595924244561, 6735.191776986695, 901.8647202919483, 1003.6437064841876, 1065.4193464561833, 1082.3938594387168, 1141.7145713571608, 31351.529727054112, 31352.21619459532, 31434.0, 32096.518552221736, 32347.191320216712, 1078.3745427651943, 1087.798757229915, 1113.059464752283, 1137.5279259107542, 1141.372245265101, 16084.437044703613, 16728.916355418387, 16855.241514131892, 17022.39449604931, 17047.48857534276, 501686.0, 521747.0, 523239.8380080981, 523251.0, 524717.0, 47823.76088119537, 53918.7304063485, 55482.722586387616, 55487.445125549035, 60931.39068609257, 1295.7019885426405, 1301.1867582760774, 1324.854266030755, 1333.1268019447277, 1333.2134040915905, 30167.21874126994, 33074.83462582672, 33115.66884331126, 33219.33561328808, 33356.833215833765, 50395.0, 51078.0, 51084.23373649324, 51136.23295650494, 51232.0, 469196.0, 469882.0, 470489.0, 471028.0, 471102.0, 38605.22789544137, 39945.60054399419, 39955.60044399635, 40000.0, 40284.39573406343, 46460.0, 46883.0, 47010.05979880452, 47181.29228061651, 47457.0, 39582.604173957894, 39917.0, 40647.39028914628, 40654.0, 41855.0, 38957.41563876601, 39719.40420893747, 40463.59536404716, 40843.0, 42074.57925420706, 40178.1964360717, 41011.0, 41116.1776764469, 41138.588614113476, 41479.0, 34500.30999380049, 35494.64505354913, 35658.28683426369, 35884.28231435409, 36586.26827463489, 27534.44931101407, 27782.722172778012, 27866.16401507958, 27951.0, 28043.57934631023], "value": 20287.440515250568, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.14092727653673, 382.8239010055378, 403.1030956122627, 403.6084997552375, 413.734801959273, 13171.670708232286, 14847.0, 15813.683726325467, 18776.12207010889, 18883.905580472114, 17294.13529323535, 17405.738913916302, 17681.64636707265, 17790.911045444787, 18184.181711822966, 5341.225522299274, 5619.5785316101255, 5623.971880140593, 5624.690642014688, 5627.915581266284, 5421.566274698034, 5541.5289700375515, 5556.77772889086, 5603.915941260884, 5611.859703507404, 5596.188552659862, 5603.439656034405, 5607.271054762875, 5609.102543593026, 5617.747201375927, 5431.158170483582, 5491.725413729318, 5492.286002819648, 5510.338759348904, 5511.145772405283, 4970.2793095001025, 4972.707096155026, 4973.5523802857915, 4975.0, 4976.0545496355935, 22430.0, 22529.395296752442, 22639.547209055727, 22669.77330226693, 22682.319530414115, 2119.3747844385944, 2155.5581105873257, 2181.3128864407818, 2237.127520267097, 2468.7654672806057, 13963.301834908214, 14219.0, 14272.644098810597, 14346.64133396663, 14931.626709332353, 1803.2156012134762, 1899.6580615489206, 1946.3650533551743, 1950.4051264364364, 1960.2159136345433, 80018.0, 80183.0, 81084.9999999994, 81817.0, 83585.58207208986, 10198.317277649254, 10316.638917637847, 10719.678409647722, 10750.301230419998, 10850.891491085145, 14191.858081419054, 14221.862251019893, 14307.427702891768, 14352.138871667734, 14352.56942291733, 7437.144728356308, 7471.775846724607, 7549.8668052758585, 7571.3942884569105, 7653.196414376522, 7081.468889833283, 7134.429245660334, 7144.821379465555, 7356.705731770776, 7382.1141463023705, 6417.711202995783, 6431.517636177306, 6466.579672321284, 6535.627518221227, 6573.211214654272, 1832.230463205457, 1865.6082222733107, 1870.551067743759, 1905.1712505060343, 1909.4080834941212, 857.352698712468, 1020.8519764634086, 1050.5745173204864, 1070.4487189097622, 1082.6643740440488, 18860.905695471436, 19728.506787330432, 19785.208591656174, 19890.0, 20189.0, 955.2978560757838, 965.1795973422675, 1017.6336518853207, 1017.97455063624, 1083.539495714334, 10907.0, 11142.275752076252, 11494.367809770543, 11514.0, 11514.251573647685, 471039.0, 477159.0, 488718.0, 489271.0, 491383.0, 45997.54002459999, 47131.05737885223, 52913.47086529162, 66281.06940750711, 77457.83813242806, 946.6639343033262, 949.1457688080644, 970.6408628807386, 974.965876194344, 975.7170420578095, 17091.401800936907, 17404.39084632032, 17858.821411785717, 18084.186211620246, 18187.727184091986, 35272.0, 37557.43663845099, 37670.8116459416, 38197.0, 39908.60091399165, 49705.0, 50130.498695014045, 50625.240621391444, 50772.0, 51021.7448912753, 8387.748367548984, 8450.873236901574, 8486.872696909428, 8669.6098675561, 8710.738677839676, 11651.00966417864, 12510.124291299522, 12936.288504132363, 13187.934060329637, 13222.801657974946, 10766.461676915966, 10856.620018299322, 11147.721306967067, 11283.0, 11334.773304534028, 10028.197744180594, 10817.729556761144, 10879.782404352027, 10979.17656175774, 11619.941900290445, 6093.9085913712215, 10345.431001295, 10596.947015265183, 11027.944860275647, 11847.881521184678, 7864.410169237216, 8132.75601731949, 8306.127856575215, 8488.363372747192, 8509.914900850912, 9330.533473326172, 10073.143782778547, 10269.691909242734, 10406.791864162826, 10459.633912813015], "value": 8877.949580112987, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.7812460943944, 1413.17329362323, 1441.4738620403552, 1550.3953458151323, 1705.1644694099887, 7413.925860741398, 8100.594970251486, 8403.453775504604, 8425.83148337033, 8660.8700869487, 8420.32637389009, 8472.110428405022, 8473.87289190661, 8488.872666909985, 8496.405251632388, 2261.2424837679355, 2280.429892526868, 2283.862968221904, 2285.0, 2285.1773361589812, 2137.3801597536694, 2203.316971738758, 2221.0338502751274, 2221.9333419997424, 2224.899879505418, 2159.276642324823, 2190.452386903274, 2219.7447293561286, 2223.288547664748, 2225.1321984426086, 2215.490437199437, 2235.9888200558976, 2239.8320125990535, 2240.0, 2243.607368710469, 2213.7824196691754, 2215.9113635454723, 2219.755826859044, 2222.5110475695483, 2227.120287486434, 8303.460275082161, 8429.072801991775, 8430.704925327645, 8480.236778689887, 8535.95732021336, 4837.588804951583, 5038.748062596856, 5039.6220283478515, 5050.318207042076, 5061.114304996611, 3105.7288620727486, 3175.9364812703616, 3240.6921342472665, 3271.5583396241677, 3278.098522906196, 989.2432289298692, 997.4214955325893, 1000.0699349604864, 1007.476112421544, 1018.5263852308638, 10899.945500272528, 11291.322520648706, 11343.149263805248, 11614.4192790361, 11616.36110013949, 4060.939085913714, 4162.5629308922735, 4240.236757383701, 4257.6168144867115, 4270.487541494978, 8798.736037918872, 8816.515091669891, 8835.337349698799, 8835.469871807712, 8852.424592401601, 4795.784189711471, 4801.423829140525, 4804.183288840905, 4806.7836947337455, 4808.951910480921, 3790.924181516354, 3996.7202295839593, 4101.2412703649325, 4102.077032667626, 4457.0194557197365, 3308.354870800171, 3599.5140656011104, 3600.1719604491113, 3612.169201083713, 3668.192997540537, 1037.8495118207968, 1067.7597540553313, 1068.7114479090758, 1087.287826473661, 1100.983485247722, 771.9112302085219, 907.2923119966437, 911.1344222988188, 928.9038934057877, 937.1659223291276, 5511.779528818883, 5747.770089196469, 5760.8271751847515, 5877.23595932526, 6121.938780612226, 936.6066252174103, 953.7377221264207, 981.1954197558076, 983.6458874805064, 1017.531935309751, 3972.2651309507833, 4142.399352093929, 4185.937210941838, 4243.702940794176, 4292.527821939584, 10137.34107283039, 10356.275060745826, 10452.634157804592, 10455.738606534745, 10471.3193642413, 10404.167666586654, 10421.478926053667, 10428.5828566858, 10436.165106791439, 10440.477976101316, 7374.967504549368, 7410.481266311306, 7477.327040566375, 7528.360089392353, 7622.733204337822, 5800.4489573490455, 5820.82537523875, 5894.2926848778425, 6024.638521688713, 6025.8192254232445, 9420.62317507292, 9459.574319155518, 9508.144267016001, 9535.570899309687, 9609.087136722279, 9228.261739060994, 9562.330636855353, 9565.0, 9602.711918642452, 9616.374935629161, 6598.307177746459, 6731.158605174353, 6998.75504357345, 7033.788986330418, 7094.184168820651, 8194.426390152632, 8282.50304981703, 8293.419460637697, 8298.170182981654, 8299.211574900375, 7108.218096009538, 7434.59109748969, 7464.402847772274, 7473.327400533978, 7536.773896783106, 7063.117110361205, 7347.8163045924275, 7365.1530074042175, 7368.778936631909, 7546.358559522502, 6828.385445309946, 6947.201071876597, 6949.722011119701, 6950.165980082423, 6955.791326260221, 4839.491853355487, 5032.672876263031, 5071.239314102914, 5123.974380128225, 5168.250603662523, 5068.442471328225, 5139.229115632685, 5152.742362881766, 5322.8935421292135, 5449.782008719607], "value": 4381.636555049672, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.4589878358615, 319.6052874699746, 321.4118163760319, 326.1389930583259, 329.2098962490024, 5015.297858299832, 5141.562967147797, 5162.870928226796, 5205.479452054793, 5269.6574722643045, 4984.950150498494, 5099.617528685345, 5124.846254612358, 5128.358955130609, 5143.717095559743, 4074.4295798588073, 4075.551689314173, 4076.9592304077023, 4082.2447847148223, 4088.2641124597562, 3989.6608788253034, 4075.3683179107284, 4075.857344992926, 4092.283850326196, 4102.138550904298, 4036.717429779902, 4045.393191021341, 4047.817848196838, 4056.1684854604882, 4058.127502586943, 4055.371417430288, 4057.4319595256393, 4060.330045542497, 4072.75563466193, 4080.12277360366, 3585.1395665040204, 3638.43604241343, 3642.1805093853936, 3644.526211592476, 3655.4882316475714, 4915.680480768739, 5034.144195486776, 5035.194368900995, 5048.596112311006, 5158.2520534522655, 4605.240135377659, 4610.654200934943, 4619.86140415788, 4622.1911165546235, 4623.630109591225, 5345.30511033567, 5355.196720491918, 5362.0, 5369.919451208235, 5381.246625472437, 3677.485152078711, 3887.1836914248042, 3890.2997460457104, 3894.9026274343087, 3899.200663863901, 5577.69322687252, 5605.215269862222, 5605.775768969236, 5607.691576963306, 5608.355039170507, 3947.2302900934615, 3959.604039596017, 3964.682825373963, 3972.7219094663674, 4003.159336539301, 3949.2891279569953, 4028.8589899353383, 4041.413994970713, 4068.4100805383646, 4071.185762847383, 4576.695641742088, 4583.793729282191, 4588.242939914877, 4602.7008244464005, 4617.607503362185, 5314.73426328682, 5397.433269506724, 5413.7834486620095, 5416.729163541807, 5497.45025497455, 1304.8173255744202, 1313.7044165062784, 1337.7993301004926, 1360.197483484729, 1376.1880490510646, 928.3223247029571, 953.6566835939057, 961.5240455974198, 962.2157941277836, 962.3648392061345, 5190.792368305301, 5192.9740351298005, 5200.323957885526, 5220.7128607926925, 5239.345081864767, 5498.862528436741, 5501.944980550224, 5503.257060296809, 5504.504594586507, 5539.612227144061, 4949.65352425334, 5122.1804511278015, 5181.611379146579, 5193.417076642624, 5202.713850738246, 2584.612308153792, 2602.4014476670473, 2610.9347266318496, 2615.398458354589, 2621.921342359732, 5065.924011139836, 5115.309433226541, 5118.181091025493, 5134.5378915897, 5182.663126896815, 4797.592204662573, 4951.653384263139, 4983.9252411213865, 4995.475475075064, 5012.398512178562, 5011.574016208663, 5038.974805126024, 5055.949440505621, 5057.848264552069, 5080.263361812516, 4828.2998965150555, 4866.270059490963, 4887.706737595756, 4914.533119353656, 4922.022176583116, 4509.661775366926, 4807.735574543433, 4818.6626936114135, 4822.9758851206925, 4826.493218212177, 4996.325496057985, 4997.900041999213, 5010.649254552146, 5011.699298042129, 5023.397192336943, 3613.512175856331, 3618.692411145082, 3621.9858439636946, 3673.522442082512, 3674.805688151308, 4602.734248081804, 4897.608191344755, 4912.557869791736, 4974.154393752999, 4994.850154495371, 4753.786079626356, 4774.761261936958, 4776.307435421914, 4781.545753153445, 4789.44921334051, 4709.858704238878, 4774.806298425394, 4782.450018247946, 4802.543758342952, 4838.371011768448, 4515.503294637653, 4692.648051396227, 4759.214729569582, 4774.594159496482, 4804.711717296834, 4777.641676874179, 4811.566958973709, 4812.975935120302, 4820.831270905642, 4859.562639362333, 4698.506656800987, 4767.69010014348, 4781.019890922283, 4783.808647654055, 4788.545088216614], "value": 3945.5223386961534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.558751224192, 743.8884167374894, 747.2303527366814, 782.1201148707704, 806.7499075286665, 14849.480268190615, 15204.847951520482, 15293.311800968955, 15315.0, 15340.769888451681, 14899.32953017114, 14970.17664028478, 14996.250187490636, 15021.173835439047, 15225.771613425808, 794.8966634337543, 799.013218674936, 799.7840583042575, 799.808046068945, 800.5676934455416, 661.6294874870088, 669.7990602819159, 777.7239080126559, 786.6066966516742, 789.7591234673422, 839.911809260028, 842.321930845669, 842.7850898020996, 843.1779015459939, 843.4306842881061, 825.1871906172415, 831.6590198018783, 833.1834801894117, 833.7540425574471, 834.1033389106734, 501.4208589079619, 508.5499333090205, 585.5783835638333, 593.4036293525023, 605.6880706436189, 14006.089604175799, 14086.15483071019, 14100.365483553265, 14103.0, 14157.787633185511, 13742.175469471864, 14624.415023399051, 14706.264686765726, 14796.18620975846, 14850.108993460426, 13140.868591314156, 13240.933795331059, 13583.592492225249, 13669.794953075712, 13831.930840345834, 4270.487541495009, 4317.503487098961, 4321.719088259285, 4388.034632380871, 4425.645948324126, 77575.0, 78604.0, 78694.60652696699, 78987.99999999943, 80451.59774201091, 11339.546418143182, 11462.885371146182, 11587.88412115885, 11748.88251117495, 11831.585894493652, 21906.671399929015, 21980.78019219787, 21994.780052199592, 22028.88985555062, 22043.559128817335, 7913.841723165505, 7937.404694647925, 8574.828503429897, 8578.656853725906, 8579.785505362415, 4541.75020373876, 4639.327297541838, 4676.205045142266, 4781.737004464788, 4808.831690890802, 3317.452620317669, 3415.846286917095, 3424.0412684448393, 3445.13871532117, 3453.6546345365264, 601.0773462734682, 610.915624766035, 619.9070139479114, 634.3181080338654, 640.6764583885108, 622.0420552349422, 630.5271046714964, 702.5621094801032, 736.3262614707589, 736.8121129112042, 9188.95405522968, 9868.506574671237, 9999.400035997864, 10004.49977501122, 10179.338343007681, 612.2010775937356, 659.7921654678761, 711.3384552366349, 719.4424321151046, 743.2975837833304, 4360.324149756792, 4703.388559487314, 4717.669763116617, 4720.858374248778, 4750.857474275777, 116966.41516792361, 118250.0, 120123.0, 123305.38347308207, 125019.3749031249, 64890.0, 76968.23031769722, 77123.0, 90215.0, 102296.48851755694, 86175.0, 90670.54664726766, 90896.0, 92593.0, 94741.5262923681, 9082.36423450352, 9130.49782261982, 9274.350795444525, 9344.95327523358, 9351.39215950961, 43199.784001081054, 44178.337324940796, 45682.771586141855, 53154.0, 53321.20018199652, 64861.0, 70029.64985175042, 71395.64302178456, 74524.0, 75176.24823751912, 13061.477540898257, 13403.195808251536, 13446.590829924551, 13449.46202151903, 13586.0, 15248.237588120328, 15408.306626202071, 15419.61450963735, 15528.767068493755, 16103.275352608927, 13749.793753093913, 13960.650983725489, 14041.946853985788, 14452.783208251674, 14712.337944792296, 14192.503262385768, 14543.127412355292, 14658.340374683377, 14674.63313417173, 14719.264036797904, 13527.120737152054, 14217.928910355382, 14334.21161836109, 14408.014843777071, 15071.397144114113, 10776.892231077902, 10917.344959302467, 10982.615608453665, 11364.943175284401, 11441.713957151138, 10632.627858024933, 11096.112311014938, 11251.324920504796, 11334.659960201207, 11438.485268162785], "value": 7533.752906538607, "unit": "score"}], "name": "dromaeo_css", "value": 7047.413056723467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 112.0, 111.0, 110.0, 110.0, 110.0, 111.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 119.0, 118.0, 179.0, 122.0, 119.0, 118.0, 117.0, 146.0, 121.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 162.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 109.0, 75.0, 76.0, 75.0, 76.0, 104.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 83.0, 82.0, 82.0, 104.0, 82.0, 82.0, 119.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 60.0, 61.0, 60.0, 59.0, 59.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 84.0, 79.0, 79.0, 79.0, 79.0, 82.0, 79.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 122.0, 131.0, 123.0, 122.0, 133.0, 133.0, 128.0, 158.0, 122.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 164.0, 166.0, 166.0, 165.0, 165.0, 165.0, 165.0, 164.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 64.0, 69.0, 63.0, 64.0, 65.0, 65.0, 64.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1487.3000000000002, "alertThreshold": 2.0}]} 09:51:05 INFO - Return code: 0 09:51:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:51:05 INFO - # TBPL SUCCESS # 09:51:05 INFO - Running post-action listener: _resource_record_post_action 09:51:05 INFO - [mozharness: 2016-09-29 16:51:05.778189Z] Finished run-tests step (success) 09:51:05 INFO - Running post-run listener: _resource_record_post_run 09:51:05 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 53248; Write bytes: 392531968; Read time: 24; Write time: 276772 09:51:05 INFO - TinderboxPrint: CPU usage
13.1% 09:51:05 INFO - TinderboxPrint: I/O read bytes / time
53,248 / 24 09:51:05 INFO - TinderboxPrint: I/O write bytes / time
392,531,968 / 276,772 09:51:05 INFO - TinderboxPrint: CPU idle
8,274.4 (86.8%) 09:51:05 INFO - TinderboxPrint: CPU user
1,204.8 (12.6%) 09:51:05 INFO - TinderboxPrint: Swap in / out
0 / 0 09:51:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103714816; Read time: 0; Write time: 74468 09:51:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288817152; Read time: 0; Write time: 202304 09:51:06 INFO - Running post-run listener: _upload_blobber_files 09:51:06 INFO - Blob upload gear active. 09:51:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:06 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:51:06 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:51:06 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:51:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:07 INFO - (blobuploader) - INFO - Done attempting. 09:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:51:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:12 INFO - (blobuploader) - INFO - Done attempting. 09:51:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:13 INFO - (blobuploader) - INFO - Done attempting. 09:51:13 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:13 INFO - Return code: 0 09:51:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9725d58da473eed8eebc348678f409a9256c25a768227e37d9cbb9753525be258060f7bd396b1e58b1bea97ef9a46e786a8629c4b800b02f19aa2be5225e18d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0edc94a5b71fe0c23b0e502fa717e489956bcd4ed979f3a0b374ee39f396807f36ab884e452e75b9ea060c58f201c892f111549d6430c49414c790b6c0076d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21ffeeac7283b11851a2ba257b02270392f13f02a79280f31e17a7dfa12181f8887cef5cf2c228b57684049a3a2e846f3a54cfda59a0770e67d47c1d6859ce6c"} 09:51:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:13 INFO - Contents: 09:51:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f9725d58da473eed8eebc348678f409a9256c25a768227e37d9cbb9753525be258060f7bd396b1e58b1bea97ef9a46e786a8629c4b800b02f19aa2be5225e18d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0edc94a5b71fe0c23b0e502fa717e489956bcd4ed979f3a0b374ee39f396807f36ab884e452e75b9ea060c58f201c892f111549d6430c49414c790b6c0076d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21ffeeac7283b11851a2ba257b02270392f13f02a79280f31e17a7dfa12181f8887cef5cf2c228b57684049a3a2e846f3a54cfda59a0770e67d47c1d6859ce6c"} 09:51:13 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:13 INFO - Copying logs to upload dir... 09:51:13 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.264641 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-29 09:51:12.528971) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 09:51:12.532104) ========= 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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 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/f9725d58da473eed8eebc348678f409a9256c25a768227e37d9cbb9753525be258060f7bd396b1e58b1bea97ef9a46e786a8629c4b800b02f19aa2be5225e18d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0edc94a5b71fe0c23b0e502fa717e489956bcd4ed979f3a0b374ee39f396807f36ab884e452e75b9ea060c58f201c892f111549d6430c49414c790b6c0076d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21ffeeac7283b11851a2ba257b02270392f13f02a79280f31e17a7dfa12181f8887cef5cf2c228b57684049a3a2e846f3a54cfda59a0770e67d47c1d6859ce6c"} build_url:https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011301 build_url: 'https://queue.taskcluster.net/v1/task/NCxhsNt7SuaWfxCOyvCxXg/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/f9725d58da473eed8eebc348678f409a9256c25a768227e37d9cbb9753525be258060f7bd396b1e58b1bea97ef9a46e786a8629c4b800b02f19aa2be5225e18d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b0edc94a5b71fe0c23b0e502fa717e489956bcd4ed979f3a0b374ee39f396807f36ab884e452e75b9ea060c58f201c892f111549d6430c49414c790b6c0076d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/21ffeeac7283b11851a2ba257b02270392f13f02a79280f31e17a7dfa12181f8887cef5cf2c228b57684049a3a2e846f3a54cfda59a0770e67d47c1d6859ce6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 09:51:12.561624) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:51:12.562220) ========= 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=ed14279e38b05397cf4aa391000001a4-1475166631.306891-943573919 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 09:51:12.645698) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 09:51:12.646031) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 09:51:12.646342) ========= ========= Total master_lag: 0.18 =========