builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1475197181.23 results: success (0) buildid: 20160929163120 builduid: 7e4f742ee50f407cb6c2b965b2c18c0a revision: 5381a4a1fefaebea41d973855a913d7959f5238b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.229694) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.230076) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.230343) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.269298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.269660) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-29 17:59:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-09-29 17:59:41 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101106 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.384310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.384590) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015941 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.434043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:41.434357) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 5381a4a1fefaebea41d973855a913d7959f5238b --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 5381a4a1fefaebea41d973855a913d7959f5238b --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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-29 17:59:41,772 truncating revision to first 12 chars 2016-09-29 17:59:41,772 Setting DEBUG logging. 2016-09-29 17:59:41,772 attempt 1/10 2016-09-29 17:59:41,772 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/5381a4a1fefa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 17:59:42,074 unpacking tar archive at: autoland-5381a4a1fefa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.650365 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:42.097800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:42.098104) ========= script_repo_revision: 5381a4a1fefaebea41d973855a913d7959f5238b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:42.098551) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:42.098866) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 17:59:42.112892) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-29 17:59:42.113263) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:59:42 INFO - MultiFileLogger online at 20160929 17:59:42 in /builds/slave/test 17:59:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 17:59:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:59:42 INFO - {'append_to_log': False, 17:59:42 INFO - 'base_work_dir': '/builds/slave/test', 17:59:42 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 17:59:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:59:42 INFO - 'branch': 'Autoland-Non-PGO', 17:59:42 INFO - 'buildbot_json_path': 'buildprops.json', 17:59:42 INFO - 'config_files': ('talos/linux_config.py',), 17:59:42 INFO - 'default_actions': ('clobber', 17:59:42 INFO - 'read-buildbot-config', 17:59:42 INFO - 'download-and-extract', 17:59:42 INFO - 'populate-webroot', 17:59:42 INFO - 'create-virtualenv', 17:59:42 INFO - 'install', 17:59:42 INFO - 'run-tests'), 17:59:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:59:42 INFO - 'download_minidump_stackwalk': True, 17:59:42 INFO - 'download_symbols': 'ondemand', 17:59:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:59:42 INFO - 'tooltool.py': '/tools/tooltool.py', 17:59:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:59:42 INFO - '/tools/misc-python/virtualenv.py')}, 17:59:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:59:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:59:42 INFO - 'installer_path': 'installer.exe', 17:59:42 INFO - 'log_level': 'info', 17:59:42 INFO - 'log_name': 'talos', 17:59:42 INFO - 'log_to_console': True, 17:59:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:59:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:59:42 INFO - 'opt_config_files': (), 17:59:42 INFO - 'pip_index': False, 17:59:42 INFO - 'sps_profile': False, 17:59:42 INFO - 'sps_profile_interval': 0, 17:59:42 INFO - 'suite': 'dromaeojs', 17:59:42 INFO - 'system_bits': '32', 17:59:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:59:42 INFO - 'title': 'talos-linux64-ix-067', 17:59:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:59:42 INFO - 'use_talos_json': True, 17:59:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:59:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:59:42 INFO - 'work_dir': 'build'} 17:59:42 INFO - [mozharness: 2016-09-30 00:59:42.541521Z] Running clobber step. 17:59:42 INFO - Running pre-action listener: _resource_record_pre_action 17:59:42 INFO - Running main action method: clobber 17:59:42 INFO - rmtree: /builds/slave/test/build 17:59:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:59:43 INFO - Running post-action listener: _resource_record_post_action 17:59:43 INFO - [mozharness: 2016-09-30 00:59:43.540761Z] Finished clobber step (success) 17:59:43 INFO - [mozharness: 2016-09-30 00:59:43.540860Z] Running read-buildbot-config step. 17:59:43 INFO - Running pre-action listener: _resource_record_pre_action 17:59:43 INFO - Running main action method: read_buildbot_config 17:59:43 INFO - Using buildbot properties: 17:59:43 INFO - { 17:59:43 INFO - "project": "", 17:59:43 INFO - "product": "firefox", 17:59:43 INFO - "script_repo_revision": "production", 17:59:43 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 17:59:43 INFO - "repository": "", 17:59:43 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 17:59:43 INFO - "buildid": "20160929163120", 17:59:43 INFO - "pgo_build": "False", 17:59:43 INFO - "basedir": "/builds/slave/test", 17:59:43 INFO - "buildnumber": 679, 17:59:43 INFO - "slavename": "talos-linux64-ix-067", 17:59:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:59:43 INFO - "platform": "ubuntu64_hw", 17:59:43 INFO - "scheduler": "tests-autoland-linux64-talos", 17:59:43 INFO - "branch": "autoland", 17:59:43 INFO - "repo_path": "integration/autoland", 17:59:43 INFO - "revision": "5381a4a1fefaebea41d973855a913d7959f5238b", 17:59:43 INFO - "stage_platform": "linux64", 17:59:43 INFO - "builduid": "7e4f742ee50f407cb6c2b965b2c18c0a", 17:59:43 INFO - "slavebuilddir": "test" 17:59:43 INFO - } 17:59:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 17:59:43 INFO - Running post-action listener: _resource_record_post_action 17:59:43 INFO - [mozharness: 2016-09-30 00:59:43.543325Z] Finished read-buildbot-config step (success) 17:59:43 INFO - [mozharness: 2016-09-30 00:59:43.543411Z] Running download-and-extract step. 17:59:43 INFO - Running pre-action listener: _resource_record_pre_action 17:59:43 INFO - Running main action method: download_and_extract 17:59:43 INFO - mkdir: /builds/slave/test/build/tests 17:59:43 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')]} 17:59:43 INFO - trying https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 17:59:43 INFO - Downloading https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/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 17:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/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 17:59:44 INFO - Downloaded 1361 bytes. 17:59:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 17:59:44 INFO - Using the following test package requirements: 17:59:44 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 17:59:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:59:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:59:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'jsshell-linux-x86_64.zip'], 17:59:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:59:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 17:59:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:59:44 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:59:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:59:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 17:59:44 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:59:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:59:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 17:59:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:59:44 INFO - Fetch https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:59:44 INFO - Http code: 200 17:59:44 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 17:59:44 INFO - x-amz-cf-id: P03xB3zhhCtFX5moamT12QLe4wAfqruMyTqmTvDunJvMQW6-W9nBGg== 17:59:44 INFO - x-amz-version-id: 37LWsqXX05KrhI0ca19RnLUeCLurE0ts 17:59:44 INFO - Expected file size: 20417649 17:59:44 INFO - Obtained file size: 20417649 17:59:48 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 17:59:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 17:59:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:59:48 INFO - Fetch https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:59:49 INFO - Http code: 200 17:59:49 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 17:59:49 INFO - x-amz-cf-id: t8wmylptNReYY8fxXZqQAj7nHMUoz-wikKVmKOI9cwcqTD5d9EsKVg== 17:59:49 INFO - x-amz-version-id: 37LWsqXX05KrhI0ca19RnLUeCLurE0ts 17:59:49 INFO - Expected file size: 20417649 17:59:49 INFO - Obtained file size: 20417649 17:59:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 17:59:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:59:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 17:59:55 INFO - Http code: 200 17:59:55 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 17:59:55 INFO - x-amz-cf-id: 8wQGioIAwwVUuo_ZO-1K-wHKoPZ7gIvD78aWVwrrGYHS8maTzlbGHQ== 17:59:55 INFO - x-amz-version-id: zJR0p4mMtCawFTNvqzoRCJ8IkSzQNsuI 17:59:55 INFO - Expected file size: 14414067 17:59:55 INFO - Obtained file size: 14414067 17:59: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')]} 17:59:56 INFO - trying https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 17:59:56 INFO - Downloading https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/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 17:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/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 17:59:58 INFO - Downloaded 55743421 bytes. 17:59:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 17:59:58 INFO - mkdir: /builds/slave/test/properties 17:59:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:59:58 INFO - Writing to file /builds/slave/test/properties/build_url 17:59:58 INFO - Contents: 17:59:58 INFO - build_url:https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 17:59:59 INFO - Running post-action listener: _resource_record_post_action 17:59:59 INFO - Running post-action listener: set_extra_try_arguments 17:59:59 INFO - [mozharness: 2016-09-30 00:59:59.070032Z] Finished download-and-extract step (success) 17:59:59 INFO - [mozharness: 2016-09-30 00:59:59.070258Z] Running populate-webroot step. 17:59:59 INFO - Running pre-action listener: _resource_record_pre_action 17:59:59 INFO - Running main action method: populate_webroot 17:59:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:59:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:59:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:59:59 INFO - '--tppagecycles', 17:59:59 INFO - '7'], 17:59:59 INFO - 'tests': ['tsvgm']}}, 17:59:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:59:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:59:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:59:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:59:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:59:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['damp', 'tps']}, 17:59:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'tests': ['damp', 'tps']}, 17:59:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['dromaeo_dom']}, 17:59:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:59:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['basic_compositor_video']}, 17:59:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:59:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'other-e10s': {'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['a11yr', 17:59:59 INFO - 'ts_paint', 17:59:59 INFO - 'tpaint', 17:59:59 INFO - 'sessionrestore', 17:59:59 INFO - 'sessionrestore_no_auto_restore', 17:59:59 INFO - 'tabpaint']}, 17:59:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['tsvgx', 17:59:59 INFO - 'tsvgr_opacity', 17:59:59 INFO - 'tart', 17:59:59 INFO - 'tscrollx', 17:59:59 INFO - 'cart']}, 17:59:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:59:59 INFO - 'tsvgr_opacity', 17:59:59 INFO - 'tart', 17:59:59 INFO - 'tscrollx', 17:59:59 INFO - 'cart']}, 17:59:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'talos_options': ['--disable-e10s'], 17:59:59 INFO - 'tests': ['tp5o']}, 17:59:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'tests': ['tp5o']}, 17:59:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'talos_options': ['--disable-e10s', 17:59:59 INFO - '--xperf_path', 17:59:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:59 INFO - 'tests': ['tp5n']}, 17:59:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:59 INFO - 'talos_options': ['--xperf_path', 17:59:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:59 INFO - 'tests': ['tp5n']}}, 17:59:59 INFO - 'talos.zip': {'path': '', 17:59:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:59:59 INFO - Running post-action listener: _resource_record_post_action 17:59:59 INFO - [mozharness: 2016-09-30 00:59:59.085807Z] Finished populate-webroot step (success) 17:59:59 INFO - [mozharness: 2016-09-30 00:59:59.085896Z] Running create-virtualenv step. 17:59:59 INFO - Running pre-action listener: _resource_record_pre_action 17:59:59 INFO - Running main action method: create_virtualenv 17:59:59 INFO - Creating virtualenv /builds/slave/test/build/venv 17:59:59 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 17:59:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:59:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:59:59 INFO - Using real prefix '/usr' 17:59:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 18:00:01 INFO - Installing pip.................done. 18:00:01 INFO - Return code: 0 18:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:00:01 INFO - Reading from file tmpfile_stdout 18:00:01 INFO - Output received: 18:00:01 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) 18:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:00:01 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')]} 18:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:01 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')]} 18:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:01 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:00:01 INFO - Using env: {'DISPLAY': ':0', 18:00:01 INFO - 'HOME': '/home/cltbld', 18:00:01 INFO - 'LANG': 'en_US.UTF-8', 18:00:01 INFO - 'LANGUAGE': 'en_US:en', 18:00:01 INFO - 'LOGNAME': 'cltbld', 18:00:01 INFO - 'MAIL': '/var/mail/cltbld', 18:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:01 INFO - 'MOZ_NO_REMOTE': '1', 18:00:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:01 INFO - 'NO_EM_RESTART': '1', 18:00:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:01 INFO - 'PWD': '/builds/slave/test', 18:00:01 INFO - 'SHELL': '/bin/bash', 18:00:01 INFO - 'SHLVL': '1', 18:00:01 INFO - 'TERM': 'linux', 18:00:01 INFO - 'TMOUT': '86400', 18:00:01 INFO - 'USER': 'cltbld', 18:00:01 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:01 INFO - '_': '/tools/buildbot/bin/python'} 18:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:00:02 INFO - Cleaning up... 18:00:02 INFO - Return code: 0 18:00:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:00: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')]} 18:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00: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')]} 18:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:02 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:00:02 INFO - Using env: {'DISPLAY': ':0', 18:00:02 INFO - 'HOME': '/home/cltbld', 18:00:02 INFO - 'LANG': 'en_US.UTF-8', 18:00:02 INFO - 'LANGUAGE': 'en_US:en', 18:00:02 INFO - 'LOGNAME': 'cltbld', 18:00:02 INFO - 'MAIL': '/var/mail/cltbld', 18:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:02 INFO - 'MOZ_NO_REMOTE': '1', 18:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:02 INFO - 'NO_EM_RESTART': '1', 18:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:02 INFO - 'PWD': '/builds/slave/test', 18:00:02 INFO - 'SHELL': '/bin/bash', 18:00:02 INFO - 'SHLVL': '1', 18:00:02 INFO - 'TERM': 'linux', 18:00:02 INFO - 'TMOUT': '86400', 18:00:02 INFO - 'USER': 'cltbld', 18:00:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:02 INFO - '_': '/tools/buildbot/bin/python'} 18:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:02 INFO - Downloading/unpacking psutil>=3.1.1 18:00: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 18:00: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 18:00: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 18:00: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 18:00: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 18:00: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 18:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:00:04 INFO - Installing collected packages: psutil 18:00:04 INFO - Running setup.py install for psutil 18:00:04 INFO - building 'psutil._psutil_linux' extension 18:00:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:00:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:00:04 INFO - building 'psutil._psutil_posix' extension 18:00:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:00:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:00:04 INFO - Successfully installed psutil 18:00:04 INFO - Cleaning up... 18:00:04 INFO - Return code: 0 18:00:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:04 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:00:04 INFO - Using env: {'DISPLAY': ':0', 18:00:04 INFO - 'HOME': '/home/cltbld', 18:00:04 INFO - 'LANG': 'en_US.UTF-8', 18:00:04 INFO - 'LANGUAGE': 'en_US:en', 18:00:04 INFO - 'LOGNAME': 'cltbld', 18:00:04 INFO - 'MAIL': '/var/mail/cltbld', 18:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:04 INFO - 'MOZ_NO_REMOTE': '1', 18:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:04 INFO - 'NO_EM_RESTART': '1', 18:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:04 INFO - 'PWD': '/builds/slave/test', 18:00:04 INFO - 'SHELL': '/bin/bash', 18:00:04 INFO - 'SHLVL': '1', 18:00:04 INFO - 'TERM': 'linux', 18:00:04 INFO - 'TMOUT': '86400', 18:00:04 INFO - 'USER': 'cltbld', 18:00:04 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:04 INFO - '_': '/tools/buildbot/bin/python'} 18:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:00:07 INFO - Installing collected packages: mozsystemmonitor 18:00:07 INFO - Running setup.py install for mozsystemmonitor 18:00:07 INFO - Successfully installed mozsystemmonitor 18:00:07 INFO - Cleaning up... 18:00:07 INFO - Return code: 0 18:00:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:07 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:00:07 INFO - Using env: {'DISPLAY': ':0', 18:00:07 INFO - 'HOME': '/home/cltbld', 18:00:07 INFO - 'LANG': 'en_US.UTF-8', 18:00:07 INFO - 'LANGUAGE': 'en_US:en', 18:00:07 INFO - 'LOGNAME': 'cltbld', 18:00:07 INFO - 'MAIL': '/var/mail/cltbld', 18:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:07 INFO - 'MOZ_NO_REMOTE': '1', 18:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:07 INFO - 'NO_EM_RESTART': '1', 18:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:07 INFO - 'PWD': '/builds/slave/test', 18:00:07 INFO - 'SHELL': '/bin/bash', 18:00:07 INFO - 'SHLVL': '1', 18:00:07 INFO - 'TERM': 'linux', 18:00:07 INFO - 'TMOUT': '86400', 18:00:07 INFO - 'USER': 'cltbld', 18:00:07 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:07 INFO - '_': '/tools/buildbot/bin/python'} 18:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:07 INFO - Downloading/unpacking jsonschema==2.5.1 18:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:00:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:09 INFO - Downloading functools32-3.2.3-2.tar.gz 18:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:00:09 INFO - warning: no files found matching '*.txt' 18:00:09 INFO - no previously-included directories found matching 'build' 18:00:09 INFO - no previously-included directories found matching 'dist' 18:00:09 INFO - no previously-included directories found matching '.git*' 18:00:09 INFO - Installing collected packages: jsonschema, functools32 18:00:09 INFO - Running setup.py install for functools32 18:00:09 INFO - warning: no files found matching '*.txt' 18:00:09 INFO - no previously-included directories found matching 'build' 18:00:09 INFO - no previously-included directories found matching 'dist' 18:00:09 INFO - no previously-included directories found matching '.git*' 18:00:09 INFO - Successfully installed jsonschema functools32 18:00:09 INFO - Cleaning up... 18:00:09 INFO - Return code: 0 18:00:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:00:09 INFO - Using env: {'DISPLAY': ':0', 18:00:09 INFO - 'HOME': '/home/cltbld', 18:00:09 INFO - 'LANG': 'en_US.UTF-8', 18:00:09 INFO - 'LANGUAGE': 'en_US:en', 18:00:09 INFO - 'LOGNAME': 'cltbld', 18:00:09 INFO - 'MAIL': '/var/mail/cltbld', 18:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:09 INFO - 'MOZ_NO_REMOTE': '1', 18:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:09 INFO - 'NO_EM_RESTART': '1', 18:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:09 INFO - 'PWD': '/builds/slave/test', 18:00:09 INFO - 'SHELL': '/bin/bash', 18:00:09 INFO - 'SHLVL': '1', 18:00:09 INFO - 'TERM': 'linux', 18:00:09 INFO - 'TMOUT': '86400', 18:00:09 INFO - 'USER': 'cltbld', 18:00:09 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:09 INFO - '_': '/tools/buildbot/bin/python'} 18:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:00:10 INFO - Cleaning up... 18:00:10 INFO - Return code: 0 18:00:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:00: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')]} 18:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00: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')]} 18:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00: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', '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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:00: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 blobuploader==1.2.4 18:00:10 INFO - Using env: {'DISPLAY': ':0', 18:00:10 INFO - 'HOME': '/home/cltbld', 18:00:10 INFO - 'LANG': 'en_US.UTF-8', 18:00:10 INFO - 'LANGUAGE': 'en_US:en', 18:00:10 INFO - 'LOGNAME': 'cltbld', 18:00:10 INFO - 'MAIL': '/var/mail/cltbld', 18:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:10 INFO - 'MOZ_NO_REMOTE': '1', 18:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:10 INFO - 'NO_EM_RESTART': '1', 18:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:10 INFO - 'PWD': '/builds/slave/test', 18:00:10 INFO - 'SHELL': '/bin/bash', 18:00:10 INFO - 'SHLVL': '1', 18:00:10 INFO - 'TERM': 'linux', 18:00:10 INFO - 'TMOUT': '86400', 18:00:10 INFO - 'USER': 'cltbld', 18:00:10 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:10 INFO - '_': '/tools/buildbot/bin/python'} 18:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:10 INFO - Downloading/unpacking blobuploader==1.2.4 18:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:12 INFO - Downloading blobuploader-1.2.4.tar.gz 18:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:00:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:00:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:12 INFO - Downloading docopt-0.6.1.tar.gz 18:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:00:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:00:12 INFO - Running setup.py install for blobuploader 18:00:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:00:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:00:13 INFO - Running setup.py install for requests 18:00:13 INFO - Running setup.py install for docopt 18:00:13 INFO - Successfully installed blobuploader requests docopt 18:00:13 INFO - Cleaning up... 18:00:13 INFO - Return code: 0 18:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:13 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')]} 18:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:13 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')]} 18:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:13 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:13 INFO - Using env: {'DISPLAY': ':0', 18:00:13 INFO - 'HOME': '/home/cltbld', 18:00:13 INFO - 'LANG': 'en_US.UTF-8', 18:00:13 INFO - 'LANGUAGE': 'en_US:en', 18:00:13 INFO - 'LOGNAME': 'cltbld', 18:00:13 INFO - 'MAIL': '/var/mail/cltbld', 18:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:13 INFO - 'MOZ_NO_REMOTE': '1', 18:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:13 INFO - 'NO_EM_RESTART': '1', 18:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:13 INFO - 'PWD': '/builds/slave/test', 18:00:13 INFO - 'SHELL': '/bin/bash', 18:00:13 INFO - 'SHLVL': '1', 18:00:13 INFO - 'TERM': 'linux', 18:00:13 INFO - 'TMOUT': '86400', 18:00:13 INFO - 'USER': 'cltbld', 18:00:13 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:13 INFO - '_': '/tools/buildbot/bin/python'} 18:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:00:13 INFO - Running setup.py (path:/tmp/pip-67IT5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:00:13 INFO - Running setup.py (path:/tmp/pip-T5nqyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:00:13 INFO - Running setup.py (path:/tmp/pip-bdtY94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:00:13 INFO - Running setup.py (path:/tmp/pip-UBwWS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:00:14 INFO - Running setup.py (path:/tmp/pip-28FZvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:00:14 INFO - Running setup.py (path:/tmp/pip-MIQePT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:00:14 INFO - Running setup.py (path:/tmp/pip-HqW1Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:00:14 INFO - Running setup.py (path:/tmp/pip-0X9S1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:00:14 INFO - Running setup.py (path:/tmp/pip-MIdNZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:00:14 INFO - Running setup.py (path:/tmp/pip-DNqvWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:00:14 INFO - Running setup.py (path:/tmp/pip-qd4p0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:00:14 INFO - Running setup.py (path:/tmp/pip-JCUDGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:00:14 INFO - Running setup.py (path:/tmp/pip-1kmtVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:00:14 INFO - Running setup.py (path:/tmp/pip-wQhd4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:14 INFO - Running setup.py (path:/tmp/pip-cYtG5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:00:14 INFO - Running setup.py (path:/tmp/pip-2OtY26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:00:14 INFO - Running setup.py (path:/tmp/pip-rQOoAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:00:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:00:15 INFO - Running setup.py install for manifestparser 18:00:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Running setup.py install for mozcrash 18:00:15 INFO - Running setup.py install for mozdebug 18:00:15 INFO - Running setup.py install for mozdevice 18:00:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Running setup.py install for mozfile 18:00:15 INFO - Running setup.py install for mozhttpd 18:00:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Running setup.py install for mozinfo 18:00:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Running setup.py install for mozInstall 18:00:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:00:15 INFO - Running setup.py install for mozleak 18:00:15 INFO - Running setup.py install for mozlog 18:00:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Running setup.py install for moznetwork 18:00:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Running setup.py install for mozprocess 18:00:16 INFO - Running setup.py install for mozprofile 18:00:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Running setup.py install for mozrunner 18:00:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Running setup.py install for mozscreenshot 18:00:16 INFO - Running setup.py install for moztest 18:00:16 INFO - Running setup.py install for mozversion 18:00:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:00:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:00:16 INFO - Cleaning up... 18:00:16 INFO - Return code: 0 18:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:16 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:16 INFO - Using env: {'DISPLAY': ':0', 18:00:16 INFO - 'HOME': '/home/cltbld', 18:00:16 INFO - 'LANG': 'en_US.UTF-8', 18:00:16 INFO - 'LANGUAGE': 'en_US:en', 18:00:16 INFO - 'LOGNAME': 'cltbld', 18:00:16 INFO - 'MAIL': '/var/mail/cltbld', 18:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:16 INFO - 'MOZ_NO_REMOTE': '1', 18:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:16 INFO - 'NO_EM_RESTART': '1', 18:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:16 INFO - 'PWD': '/builds/slave/test', 18:00:16 INFO - 'SHELL': '/bin/bash', 18:00:16 INFO - 'SHLVL': '1', 18:00:16 INFO - 'TERM': 'linux', 18:00:16 INFO - 'TMOUT': '86400', 18:00:16 INFO - 'USER': 'cltbld', 18:00:16 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:16 INFO - '_': '/tools/buildbot/bin/python'} 18:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:00:17 INFO - Running setup.py (path:/tmp/pip-OGf9Fp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:00:17 INFO - Running setup.py (path:/tmp/pip-07ZCWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:00:17 INFO - Running setup.py (path:/tmp/pip-QJzoRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:00:17 INFO - Running setup.py (path:/tmp/pip-hFipN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:00:17 INFO - Running setup.py (path:/tmp/pip-3BFGxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:00:17 INFO - Running setup.py (path:/tmp/pip-v_s6AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:00:17 INFO - Running setup.py (path:/tmp/pip-TWz8c4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:00:17 INFO - Running setup.py (path:/tmp/pip-i9kouB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:00:17 INFO - Running setup.py (path:/tmp/pip-_4MpTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:00:17 INFO - Running setup.py (path:/tmp/pip-OasdxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:00:17 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)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:00:17 INFO - Running setup.py (path:/tmp/pip-_ru301-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:00:17 INFO - Running setup.py (path:/tmp/pip-q7h3ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:00:17 INFO - Running setup.py (path:/tmp/pip-2CodX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:00:17 INFO - Running setup.py (path:/tmp/pip-k_1R5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:18 INFO - Running setup.py (path:/tmp/pip-tW9bR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:00:18 INFO - Running setup.py (path:/tmp/pip-AjgMf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:00:18 INFO - Running setup.py (path:/tmp/pip-iU2JUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:20 INFO - Downloading blessings-1.6.tar.gz 18:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:00:20 INFO - Installing collected packages: blessings 18:00:20 INFO - Running setup.py install for blessings 18:00:20 INFO - Successfully installed blessings 18:00:20 INFO - Cleaning up... 18:00:20 INFO - Return code: 0 18:00:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:00:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:00:20 INFO - Reading from file tmpfile_stdout 18:00:20 INFO - Current package versions: 18:00:20 INFO - argparse == 1.2.1 18:00:20 INFO - blessings == 1.6 18:00:20 INFO - blobuploader == 1.2.4 18:00:20 INFO - docopt == 0.6.1 18:00:20 INFO - functools32 == 3.2.3-2 18:00:20 INFO - jsonschema == 2.5.1 18:00:20 INFO - manifestparser == 1.1 18:00:20 INFO - mozInstall == 1.12 18:00:20 INFO - mozcrash == 0.17 18:00:20 INFO - mozdebug == 0.1 18:00:20 INFO - mozdevice == 0.48 18:00:20 INFO - mozfile == 1.2 18:00:20 INFO - mozhttpd == 0.7 18:00:20 INFO - mozinfo == 0.9 18:00:20 INFO - mozleak == 0.1 18:00:20 INFO - mozlog == 3.4 18:00:20 INFO - moznetwork == 0.27 18:00:20 INFO - mozprocess == 0.23 18:00:20 INFO - mozprofile == 0.28 18:00:20 INFO - mozrunner == 6.12 18:00:20 INFO - mozscreenshot == 0.1 18:00:20 INFO - mozsystemmonitor == 0.3 18:00:20 INFO - moztest == 0.7 18:00:20 INFO - mozversion == 1.4 18:00:20 INFO - psutil == 3.1.1 18:00:20 INFO - requests == 1.2.3 18:00:20 INFO - wsgiref == 0.1.2 18:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:20 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:20 INFO - Using env: {'DISPLAY': ':0', 18:00:20 INFO - 'HOME': '/home/cltbld', 18:00:20 INFO - 'LANG': 'en_US.UTF-8', 18:00:20 INFO - 'LANGUAGE': 'en_US:en', 18:00:20 INFO - 'LOGNAME': 'cltbld', 18:00:20 INFO - 'MAIL': '/var/mail/cltbld', 18:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:20 INFO - 'MOZ_NO_REMOTE': '1', 18:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:20 INFO - 'NO_EM_RESTART': '1', 18:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:20 INFO - 'PWD': '/builds/slave/test', 18:00:20 INFO - 'SHELL': '/bin/bash', 18:00:20 INFO - 'SHLVL': '1', 18:00:20 INFO - 'TERM': 'linux', 18:00:20 INFO - 'TMOUT': '86400', 18:00:20 INFO - 'USER': 'cltbld', 18:00:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:20 INFO - '_': '/tools/buildbot/bin/python'} 18:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:00:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:00:20 INFO - Cleaning up... 18:00:20 INFO - Return code: 0 18:00:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:20 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 0x7feef5ad4f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b4ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:00:20 INFO - Using env: {'DISPLAY': ':0', 18:00:20 INFO - 'HOME': '/home/cltbld', 18:00:20 INFO - 'LANG': 'en_US.UTF-8', 18:00:20 INFO - 'LANGUAGE': 'en_US:en', 18:00:20 INFO - 'LOGNAME': 'cltbld', 18:00:20 INFO - 'MAIL': '/var/mail/cltbld', 18:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:20 INFO - 'MOZ_NO_REMOTE': '1', 18:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:20 INFO - 'NO_EM_RESTART': '1', 18:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:20 INFO - 'PWD': '/builds/slave/test', 18:00:20 INFO - 'SHELL': '/bin/bash', 18:00:20 INFO - 'SHLVL': '1', 18:00:20 INFO - 'TERM': 'linux', 18:00:20 INFO - 'TMOUT': '86400', 18:00:20 INFO - 'USER': 'cltbld', 18:00:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:20 INFO - '_': '/tools/buildbot/bin/python'} 18:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:00:21 INFO - Cleaning up... 18:00:21 INFO - Return code: 0 18:00:21 INFO - Running post-action listener: _resource_record_post_action 18:00:21 INFO - Running post-action listener: _start_resource_monitoring 18:00:21 INFO - Starting resource monitoring. 18:00:21 INFO - [mozharness: 2016-09-30 01:00:21.119847Z] Finished create-virtualenv step (success) 18:00:21 INFO - [mozharness: 2016-09-30 01:00:21.120358Z] Running install step. 18:00:21 INFO - Running pre-action listener: _resource_record_pre_action 18:00:21 INFO - Running main action method: install 18:00:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:00:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:00:21 INFO - Reading from file tmpfile_stdout 18:00:21 INFO - Detecting whether we're running mozinstall >=1.0... 18:00:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:00:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:00:21 INFO - Reading from file tmpfile_stdout 18:00:21 INFO - Output received: 18:00:21 INFO - Usage: mozinstall [options] installer 18:00:21 INFO - Options: 18:00:21 INFO - -h, --help show this help message and exit 18:00:21 INFO - -d DEST, --destination=DEST 18:00:21 INFO - Directory to install application into. [default: 18:00:21 INFO - "/builds/slave/test"] 18:00:21 INFO - --app=APP Application being installed. [default: firefox] 18:00:21 INFO - mkdir: /builds/slave/test/build/application 18:00:21 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'] 18:00:21 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 18:00:37 INFO - Reading from file tmpfile_stdout 18:00:37 INFO - Output received: 18:00:37 INFO - /builds/slave/test/build/application/firefox/firefox 18:00:37 INFO - Running post-action listener: _resource_record_post_action 18:00:37 INFO - [mozharness: 2016-09-30 01:00:37.536063Z] Finished install step (success) 18:00:37 INFO - [mozharness: 2016-09-30 01:00:37.536310Z] Running run-tests step. 18:00:37 INFO - Running pre-action listener: _resource_record_pre_action 18:00:37 INFO - Running main action method: run_tests 18:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:00:37 INFO - Python 2.7.3 18:00:37 INFO - Return code: 0 18:00:37 INFO - grabbing minidump binary from tooltool 18:00:37 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')]} 18:00:37 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 0x21b1f60>, '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 0x21b2d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21b31d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:00:37 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:00:37 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:00:37 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:00:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:00:37 INFO - Return code: 0 18:00:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:00:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:00:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:00:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:00:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:00:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:00:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:00:37 INFO - Using env: {'DISPLAY': ':0', 18:00:37 INFO - 'HOME': '/home/cltbld', 18:00:37 INFO - 'LANG': 'en_US.UTF-8', 18:00:37 INFO - 'LANGUAGE': 'en_US:en', 18:00:37 INFO - 'LOGNAME': 'cltbld', 18:00:37 INFO - 'MAIL': '/var/mail/cltbld', 18:00:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:00:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:00:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:37 INFO - 'MOZ_NO_REMOTE': '1', 18:00:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:00:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:37 INFO - 'NO_EM_RESTART': '1', 18:00:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:37 INFO - 'PWD': '/builds/slave/test', 18:00:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:00:37 INFO - 'SHELL': '/bin/bash', 18:00:37 INFO - 'SHLVL': '1', 18:00:37 INFO - 'TERM': 'linux', 18:00:37 INFO - 'TMOUT': '86400', 18:00:37 INFO - 'USER': 'cltbld', 18:00:37 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277', 18:00:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:37 INFO - '_': '/tools/buildbot/bin/python'} 18:00:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:00:37 INFO - mozversion application_buildid: 20160929163120 18:00:37 INFO - mozversion application_changeset: 5381a4a1fefaebea41d973855a913d7959f5238b 18:00:37 INFO - mozversion application_display_name: Nightly 18:00:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:00:37 INFO - mozversion application_name: Firefox 18:00:37 INFO - mozversion application_remotingname: firefox 18:00:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:00:37 INFO - mozversion application_vendor: Mozilla 18:00:37 INFO - mozversion application_version: 52.0a1 18:00:37 INFO - mozversion platform_buildid: 20160929163120 18:00:37 INFO - mozversion platform_changeset: 5381a4a1fefaebea41d973855a913d7959f5238b 18:00:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:00:37 INFO - mozversion platform_version: 52.0a1 18:00:37 INFO - using testdate: 1475197237 18:00:37 INFO - actual date: 1475197237 18:00:37 INFO - starting webserver on 'localhost:53265' 18:00:37 INFO - SUITE-START | Running 2 tests 18:00:37 INFO - TEST-START | dromaeo_css 18:00:37 INFO - operating with platform_type : linux_ 18:00:37 INFO - Initialising browser for dromaeo_css test... 18:00:37 INFO - TEST-INFO | started process 9031 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp47lZgt/profile http://localhost:53265/getInfo.html) 18:00:42 INFO - PROCESS | 9031 | __metrics Screen width/height:1600/1200 18:00:42 INFO - PROCESS | 9031 | colorDepth:24 18:00:42 INFO - PROCESS | 9031 | Browser inner width/height: 1024/697 18:00:42 INFO - PROCESS | 9031 | __metrics 18:00:42 INFO - PROCESS | 9031 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:00:42 INFO - PROCESS | 9031 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:00:43 INFO - PROCESS | 9031 | console.error: PushService: 18:00:43 INFO - PROCESS | 9031 | stateChangeProcessEnqueue: Error transitioning state 18:00:43 INFO - PROCESS | 9031 | UnknownError 18:00:43 INFO - TEST-INFO | 9031: exit 0 18:00:43 INFO - Browser initialized. 18:00:43 INFO - Running cycle 1/1 for dromaeo_css test... 18:00:43 INFO - TEST-INFO | started process 9116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp47lZgt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:00:56 INFO - PROCESS | 9116 | RSS: Main: 175206400 18:00:56 INFO - PROCESS | 9116 | 18:01:46 INFO - PROCESS | 9116 | 1475197306839 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:04:00 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53265/tests/dromaeo/cssquery-ext.html) 18:04:01 INFO - PROCESS | 9116 | RSS: Main: 294473728 18:04:01 INFO - PROCESS | 9116 | 18:07:04 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-ext.html (next: http://localhost:53265/tests/dromaeo/cssquery-jquery.html) 18:07:05 INFO - PROCESS | 9116 | RSS: Main: 271065088 18:07:05 INFO - PROCESS | 9116 | 18:10:07 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53265/tests/dromaeo/cssquery-mootools.html) 18:10:08 INFO - PROCESS | 9116 | RSS: Main: 219017216 18:10:08 INFO - PROCESS | 9116 | 18:13:10 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53265/tests/dromaeo/cssquery-prototype.html) 18:13:11 INFO - PROCESS | 9116 | RSS: Main: 242974720 18:13:11 INFO - PROCESS | 9116 | 18:16:14 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53265/tests/dromaeo/cssquery-yui.html) 18:16:14 INFO - PROCESS | 9116 | RSS: Main: 221356032 18:16:14 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | Cycle 1(1): loaded http://localhost:53265/tests/dromaeo/cssquery-yui.html (next: http://localhost:53265/tests/dromaeo/cssquery-dojo.html) 18:19:17 INFO - PROCESS | 9116 | RSS: Main: 309534720 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | __start_tp_report 18:19:17 INFO - PROCESS | 9116 | _x_x_mozilla_page_load 18:19:17 INFO - PROCESS | 9116 | _x_x_mozilla_page_load_details 18:19:17 INFO - PROCESS | 9116 | |i|pagename|runs| 18:19:17 INFO - PROCESS | 9116 | |0;dojo.html;2597.2467984284553;2620.2532278300687;2650.867456627168;2654.4558365535067;2670.078822806131;4626.097910907374;4713.198756211444;4730.739809310491;4810.831620893269;4847.709137451755;4675.485696573374;4718.4573774016;4726.787294571744;4726.787294571744;4752.358431611731;4315.525292217853;4345.8478953236645;4367.803448844809;4383.715058521186;4432.3573081903105;4445.333200019991;4445.911081778362;4469.888252793674;4486.685931984763;4535.138323718499;4273.059926816096;4325.134973005412;4344.435223420966;4386.539413361615;4398.098389830077;4362.105768317519;4376.102898905717;4381.036172042146;4383.868483945486;4385.605295523386;4287.742735435884;4330.523642399334;4351.238533256667;4354.586314300136;4360.847370342023;4715.104130215283;4720.197566413718;4787.832425865078;4791.089692958327;4817.277408388734;5071.264666623356;5071.847844564669;5073.695578265316;5078.7460626968505;5131.692098474066;4927.039227350632;4981.825636102755;5074.949250507485;5075.390953085616;5115.2327150927285;4700.670953033323;4733.881652958635;4739.834105806281;4745.074710431432;4807.230843065093;5194.636375453758;5195.506426889439;5197.610179236573;5201.661891977085;5204.063268611686;4859.34398856157;4986.152354099811;4991.875203119951;4993.650444468925;4995.050940321327;4984.576311013604;5076.974615126975;5078.822241221539;5105.106606343875;5120.436751957282;4977.726225057657;5024.62315326352;5027.572656324181;5093.1087059764395;5155.690658560424;5062.417821950523;5070.974645126751;5071.746412679351;5078.873028174251;5130.153524668388;4274.84579163624;4303.548127446636;4308.547602501755;4386.429764130643;4397.362382454527;3812.218495208476;3812.714046446472;3815.4086116651965;3850.3839385698716;3865.5168103987003;4343.457067866517;4371.5628437156665;4377.124575084996;4382.320740285261;4437.733735975852;4898.657094003457;4955.231939049453;5090.261912022736;5126;5133.050385678662;4336.349547567827;4360.978195109004;4362.280223763044;4394.12117576486;4415.62467190286;4617.215073437524;4630.467496237909;4632.096741135513;4638.930416043762;4679.3916790816975;4669.976650116728;4714.740689262124;4769.189237829647;4781.545753153445;4819.975900120477;5151.407588127413;5154.407243167084;5158.09733296679;5205.765740541609;5222.3472065991755;4801.423829140525;4810.158222311012;4812.326274321527;4814.638902082428;4871.050145221648;4789.329493870791;4820.638452116035;4913.336699545515;5067.974660126823;5146.948530514647;4943.307936888767;4978.651494395358;4982.925256121233;5039.193729003342;5044;4828.179209534317;4835.95164048355;4840.588549973291;4844.32179494864;4901.264810278344;4754.263089221176;4786.7845946931775;4822.421309442749;4894.951050489592;4937.358143441474;4913.361263035782;4966.354373931365;4973.751312434436;5012.824551140839;5027.472115427974;5033.92449113271;5077;5083.644144909821;5091.338126043591;5146.871328216825;4794.568488836021;4835.492273311393;4845.41854977405;4846.224604063333;4963.4540200577185 18:19:17 INFO - PROCESS | 9116 | |1;ext.html;568.3208565763914;570.3953808962498;577.2092233639913;577.6129992904754;578.560294176426;27262.727372726265;38200;39316.80341598292;39362.8031859841;43281.78359108208;39535;39976.400353994715;40035.799821000925;40040;40091.19817603654;7667.2716091971315;7786.416018798584;7800.921990780104;7813.062432508107;7814.804629884242;7571.697132114737;7654.693812247531;7665.961670191641;7666.655000524962;7668.233176682343;7653.540787552764;7716.575588342639;7720.459567830254;7734.806629834271;7741.303282704555;7660.961695191516;7695.384369250474;7710.1904300048245;7716.035495563055;7723.613819309012;7591.8861217081785;7644.617769111522;7645.08259008917;7646.923530764677;7655.540667559964;37745;37866;38042.42936355957;38134;38137;3008;3008.74425673818;3033.7876348655604;3042.087373787877;3051.343961048385;27394.45211095767;27436.862815685792;27879.442411151664;27980.720192798017;28674.283142921384;3154.3375891062906;3170.619525656936;3206;3209.406259841937;3276.950845737316;249208;249381;249723.9999999982;250654;251092;51730.741346293784;52085.739571302656;52156;52183.73908130435;52189.47810521846;32142.83928580342;33044;33128.83435582807;55410;55586.7164574555;14565;14956.775648365283;14973.176475293963;14974.176420296988;15066.849331506544;11378.886211137782;11469.942650286695;11478.770424591461;11480.827787583194;11487;17630.294788208328;17705.29178832858;17716.82283177152;17786.37747678825;17806.91096544535;6612.2726500084955;6674.599524028574;6732.225794033651;6733.293004234582;6835.658217089225;758.3402439877342;841.1798496465897;898.8606765951288;908.2733812949614;908.318760929303;26601.46797064048;27795.383431136008;28134;28362.43275134486;28407.57388639172;726.4551586310267;877.3156937588691;911.6307895302413;913.5660561233494;953.9856902146473;14408.783868241984;14688;14712.411503539739;14795.408183672747;14932.776008359884;363335;366179;366577;366594;366807;48671.75664121657;49179.262311065366;49291.26063109056;49337.50662493329;49375.50624493709;1052.8631277933819;1074.5272080284649;1133.7222380516826;1136.8749437561862;1179.0604455670546;27417.31456713598;28576.714232858238;29322.680479378905;29524.114276571738;30071.398572028873;41469.585304146574;42057.158856823306;42068.36897446479;42103.57896421119;42154;291013;292187;293476;293503;293907;25076.62385064262;26181.34546636273;26380.8680956594;26487.072952447343;26499.867500663146;40517.1896562073;41012.58987410088;41058.1788364237;41062.79468602638;41552.79223603863;33804.66195338113;34108.37211860015;34519.20288768183;34617.30765384628;34661.30677386488;34671.65328346684;35078.824605876805;35270.64729352674;35327.47008794819;35397;34753;35005.199584033726;35041.824790875886;35320.47019294764;35969.28061438809;30495.847520762254;32157.35685286328;32263.677363227005;32416.83791581027;32746.34507309888;24211.39471513226;25041.12356067529;25052;25061.05112378775;25376.238712838644 18:19:17 INFO - PROCESS | 9116 | |2;jquery.html;339.9762016658835;359.46978207144457;359.95320608320895;363.8290003698262;378.9450529673197;12183.634490965274;13164.605061848148;15278.618034549128;16049.197540122976;17398.30406783727;16325.428609998653;16434.342626294932;16724.83275167248;16969.745453818203;17145.228464719086;4779.952200478003;4842.685225460343;4984.327115839352;5046.621503387242;5048.040872234264;4951.207806750921;5066.36670416198;5069.670471419365;5071.1632580624155;5079.593632509409;3612.422012478004;3821.12114213731;5442.25847728727;5688.715564221793;5703.372629010805;5080.314157588715;5787.276590426197;5796.188533605298;5796.565257605696;5798.942010579883;5110.6422550421485;5249.1076516991825;5255.500727430888;5256.3967989763105;5259.579233661298;11371.943140284246;11465.656030319104;11522.251053681484;11716.765664686744;11909.404529773477;1821.5355084453513;1858.1173942377397;1926.1621194780175;1930.4112245765036;1957.8237958583654;8951.820963580692;9098.95362033368;9250.953745231298;9503.477308748015;9972.750681232954;1643.5644554193148;1713.6658351621438;1737.148797089417;1776.7157254839165;1801.927922883083;62540;67110.3288967109;67408;68135.60329792276;71261.2873871265;9333.626654933862;9595.808083838285;9835.901640983642;9851.113399794052;9917.950410248048;13184.538541151014;13363.933180334036;13391.263480508665;13406.128601640861;13415.329233538285;6810.727570897108;6917.827054323682;7117.572945623279;7119.75080872167;7149.106361704787;6649.534532582769;6711.664416779142;6718.361755633207;6880.071190389233;6959.26927672587;3695.7782533048103;3757.2485502898985;3918.5305510433586;3987.7009224308295;4047.4738284022887;1661.8836681432183;1712.5804177976474;1757.2180379731153;1841.992228470973;1842.6222624361974;815.0260438775724;825.5913322905201;851.8211175653058;875.7066382761719;886.3529623374973;15569.532914012598;16384;16473.752893706605;16934.237959291862;17318.567035824206;810.6554714246539;814.5601375257413;828.3952714518435;834.7746108550779;896.8430524658289;6974.755883544051;9261.629534818532;9428.481433521221;9522.285828562746;9614.634877792856;367826;375793;382316;383508;383726;75639.62180189064;78441.60779196068;79141;80036;80077.59961200156;835.4043776387193;867.4795122926193;870.299408975768;877.1141147441003;879.1252703559999;14712.485063022743;14737.778933315794;14780.48268310604;14825.703485930006;15461.690766184389;36567.45148822717;37823.81088094542;37920.43119353267;38107;38193.80903095467;43795.34306985334;46702.53297466982;47000.529994700984;47343.526564735286;48102.75948620235;7221.4222862171955;7379.852402952019;7415.665180267603;7543.3965282778345;7636.121845987783;9228.261739060725;10547.314424562379;10991.175661825235;11413.429328533706;12089.214201076902;9200.815983680423;10055.89944100579;10124.797504050026;10223.948880255552;10922.563097476012;10243.334183278062;10490.685279441628;10594.682159535227;10604.628837990633;10716.410597417216;10286;10338.224633152695;10660.786784264426;10835.783284334428;11136.164787640797;7426.888596670946;7447.329740323397;7487.925120748723;7662.923370766444;7751.651175696994;10401.167906567607;10730.946345268223;10797.24419290642;11033.668989930315;11248.156388270749 18:19:17 INFO - PROCESS | 9116 | |3;mootools.html;1316.0787448785852;1347.6967682271486;1366.5353779714894;1624.5126462061378;1664.875134364923;7155.928440715591;7647.847043059142;7656.272654097866;7663.310302072812;7707.075150981883;6504;6737.359950804678;7142.821429464266;7363.153237377704;7386.852262954738;2127.7872212778716;2154.1814110638015;2154.806067453929;2156.0272350799;2158.384860314811;1746.5109769264627;2130.0521268035654;2147.914083436661;2179.3788770200495;2186.431527802767;1828.2961059991867;2055.825254853339;2132.6694362373855;2133.0614529606923;2138.893055347234;2064.4735592423986;2067.7415323084615;2071.927482538119;2072.336852207302;2085.9061342239634;2068.048697599106;2073.7200477935435;2083.7603675577197;2084.249670118756;2084.7602525709435;7415.740449084256;7501.737439189602;7508.061492313461;7514.286142816479;7527.924720752777;4451.1765323415275;4616.053708989649;4619.284010978304;4630.675852690313;4633.027064316498;2760.0753747494614;2913.490139225627;2916.125162451256;2924.166612515423;2926.619539459878;869.9782505437352;924.7549399409162;925.5187302602609;928;938.938968967015;10019.949900250525;10036.14692751117;10060.346077504939;10068.899311006942;10071.798564028752;3770.264798364293;3878.398848178537;4019;4086.264472394938;4091.7954102294766;8709.433886797338;8709.956450217709;8720.171583699539;8721.738347849574;8728.694495692747;4509.210888094571;4509.549045095464;4509.729416235036;4509.751963641966;4511.909761804745;4003.179348233605;4137.379393091001;4139;4140.565240649748;4141.461609990682;3456;3528.9117772055392;3574.12433953683;3581.5881173664843;3587.121155316964;1060.2684147937898;1167.6613782003294;1170.9063274937985;1181.5451051345294;1185.508014174108;678.5792808458788;733.4719002318323;742.7177672484421;766.5515673331053;779.9532028078333;4784.593309568726;4838.080764654705;4861.416630004422;4890.633202509826;4937.728424936591;795.7016118955391;849.422392772908;869.4001139213922;882.9381943264038;949.9572519236651;3439.226174110855;3478.652134786552;3493.737969652286;3583.1400463889;3621.4748861414946;8988.55057247135;9107.544622768835;9252.074792520829;9276.257899368034;9295;8260.917390826135;9050.86423703645;9071.954640226888;9199.678011269574;9218.649244442624;6513.804585862432;6773.695183716744;6788.660566971632;6793.320667933167;6796.796096117125;4850.513275220477;4910.263460480956;4945.901081978413;5045.798168073383;5229.032628963654;8329.50022998622;8463.36524760633;8528.317734581095;8585.35222789193;8674.262687671619;8552.828943421222;8657.047724750399;8686.609102590524;8695.565221739014;8762.07998160184;6500.642464664489;6626.337366263335;6627.3372662733345;6631.038499417654;6633.2040155181685;7234.819129521804;7241.891371629325;7273.199948005821;7281.963590182015;7290.854182916418;5148.716820574904;6503.772367967098;6587.835304117435;6601.075849381186;6726.966365168339;6479.935200647933;6524.608523488606;6545.443637290884;6563.212414510289;6577.3422657735755;5510.173473978935;5599.664020158803;5607.775688972395;5609.607327487037;5611.382747897646;4121.093359460914;4145.813438395219;4252.128313695747;4255.978565144405;4368.912621747484;4235.597618226264;4261.254280500838;4300.37644541546;4329.307310830378;4417.1386579616665 18:19:17 INFO - PROCESS | 9116 | |4;prototype.html;285.08061501657147;287.9064304101167;295.6998646373931;296.78186532898314;302.3771031674749;4653.9534604653945;4806.423229212491;4864.221724524077;4952.232403977389;4965.925511117332;4656.9534304656945;4768.809247630091;4783.4453802514145;4807.788417328034;4840.903181936368;3807.409851473012;3810.1998580298173;3826.8660596879113;3831.4252862070703;3833.13754405258;3559.394902866519;3593.2633810068874;3825.674817640504;3844.442555829403;3847.6537111659945;3793.222389410178;3812.2184952084895;3816.751911125768;3820.8280627371696;3822.73240873139;3779.6031416701126;3822.4266360045935;3828.444875493066;3830.942535861964;3831.750936189139;3345.866165353383;3346.5482159908343;3360.4119279126057;3363.5795525559306;3374.696277335027;4580.381648477446;4677.368555245032;4722.6221902247735;4733.4793172751315;4753.073150735608;4315.611594956437;4318.784060796979;4324.070324880135;4328.935065974013;4342.6742994275555;5019.494151754496;5080.949190508085;5097.49025097491;5098.311727916721;5111.667741596784;3581.4627805829073;3632.4551317302353;3648.3980143276326;3695.6858667013335;3699.9445008324897;5258.475931552838;5267.973660131714;5280.656757310801;5285.286486324374;5321.574274058066;3737.5328083989502;3740.36413809653;3747.362948298796;3751.81817727415;3755.830987605564;3783.9243215136094;3812.7712337259854;3817.7019813263623;3832.080300727805;3837.6354246346555;4174.83300667976;4255.638270746959;4262.914741705148;4290;4326.956730432718;5023.572996295283;5029.59763218949;5043.798248070036;5051.797928082836;5057.595392368602;1208.5045131496045;1244.7012716948047;1263.9368031598567;1282.5891519328663;1291.793313069923;877.0133599700337;882.885224920769;896.090468174803;899.6864577716476;907.9409838360485;4340.175366680321;4398.626116780046;4398.890027749332;4413.514513403523;4420.497031009408;5101.775573862246;5103.668261562986;5132.358455193101;5136.717480538607;5140.717260550631;4341.978290108593;4350.58669426404;4356.259435895905;4360.302351623725;4372.103718737651;2311.2719493359537;2416.673749043892;2420.7337192908767;2423.7333893271725;2428.1865575032207;4257.659387249013;4266.786660667016;4286.171127056128;4295.334223195409;4310.418093557392;4619.175425706894;4634.72191668501;4638;4650.6048185544205;4671.789769460383;4062.4000639590686;4245.80893859777;4250.744955302692;4287.742735435884;4307.353896915488;4489.765314538528;4505.121501307213;4522.4346956630425;4522.914500519917;4554.977225113986;4449.621782148553;4481.498697936097;4489;4507.3689683445;4556;4031.5162180538523;4252.468441444819;4261.850835220753;4268.2743933530755;4284.258074832048;3342.298117395374;3362.5796775403073;3369.871093183811;3376.2403459221478;3392.728581713506;4552.408186935677;4600.539946005372;4627.722336659676;4638.652101092499;5004.599632029502;4864.391951006124;5026.824061157842;5069.1635880079375;5085.288059671575;5100.795968161232;5040.320565986924;5040.672356296829;5067.594592432522;5069.569086627531;5070.2141168119;4763.618910487222;4947.431045429822;4981.676191047571;4995.275685025578;5024.698518088926;5056.696598204119;5085.440191865783;5092.567131793839;5095.210242412432;5115.462876397924;4976.402831660224;4997.225430058346;5025.597952163745;5064.797408103635;5085.4914508548845 18:19:17 INFO - PROCESS | 9116 | |5;yui.html;598.4494265275946;600.2556829531381;614.1034090228267;645.4900628503485;699.2413231643667;12972.675683107927;14237.216953067576;14268.643283917907;14318.140911545315;14396.208208548538;13395.263260520667;13523.729525409486;13527.323633818294;13527.661808454794;13534.120282181651;607.5413063137347;624.8812725582147;741.3772431157827;748.4798065344575;764.2510339866934;771.4098714483424;773.5822655765879;774.1329710723998;774.3031271855347;775.3758224629171;800.4636893281486;807.2331285278979;808.4785313472806;809.0938149272823;809.324214281076;645.7868903261935;661.8874791285493;783.7962129846225;797.1231645190302;803.5500119932827;589.9911501327483;590.1295589006224;590.2268028882178;590.2828063902336;590.5718354193176;12397.380130993415;12429.440675169639;12430.067744919077;12434.564790232389;12435;11785.764284714343;11982.281063136152;12287;12441.440135193847;12753.362331883369;10532.473376331229;10559.577616895314;10781.299215550964;11347.886521134766;11631.941840290829;4080.1843179785005;4094.3653733671326;4165.10450253197;4298.183345164409;4305.461817272841;63561.68219158875;64250;66639;67270.66364668195;67740.6612966937;10077.09306162449;10210.897891021143;10239.539220735085;10314.587416503256;10424.113950314157;18690.906545467187;19673.409797706092;19904.203831846855;19933.401997940084;19947.20211191536;6453.870922581522;6512.934870651233;6546.541742078009;6559.7376104955265;6727.798166055026;3458.4466485362223;3480.512728218001;3507.596626387947;3528.541289632383;3538.575370955502;2752.587111933226;2810.6627204735564;2814.2120206341847;2821.2664707175873;2847.886084556636;470.5741304119777;471.92921061840315;475.6717864673364;481.59786578207587;485.4587135344137;557.9246801681721;566.963147395418;572.3860788587824;586.9119632055226;587.8883012227749;7405.2224516425;7612.505187162817;7919.564423956623;8003.759887203393;8077.151899050515;554.9833504994857;558.5503669546055;576.8327185116269;588.9234399528034;589.548995018811;3565.2512972276113;3698.6856117230336;3753.7184711146774;3906.140649057203;3944.980275098606;100677.49661251646;100862;103529;104795;105934;84750.57624711837;85991;86748.132518674;87147.56426217829;89958.55020724985;74178.62910685412;74886.62556687182;75414.62292688501;75833;77022;7096.680649370687;7137.321954414322;7648.579328137006;7721.729739459092;7725.690972361042;37937;39854.60145398509;40266.59733402746;40375.55868854482;42985.140297194506;56418;59180.40819591921;63411.36588634054;63464;63711;11220.270682405617;11319.830202546804;11324.773504529698;11511.663132384207;11623.070154387799;12473.313967731861;12550.686232844251;12821.615351539469;13077.607671769862;13641.249731264876;12491.437885295365;12522.81033301835;12633.368331583568;12717.692595829692;13621.25083120438;11789.646310610695;11956.880431195925;12221.327826969246;13377.66555836073;13491;12199.738676391205;12333.691657708629;12341.876581234432;12404.689882753004;13175;10208.948955255473;10279.640212592523;10309.278350515391;10531.94734026325;10634.840477393;9080.63677452921;9357.812843743222;9532.52337383142;9738.513074346396;10062.78618703884 18:19:17 INFO - PROCESS | 9116 | __end_tp_report 18:19:17 INFO - PROCESS | 9116 | __start_cc_report 18:19:17 INFO - PROCESS | 9116 | _x_x_mozilla_cycle_collect,982 18:19:17 INFO - PROCESS | 9116 | __end_cc_report 18:19:17 INFO - PROCESS | 9116 | __startTimestamp1475198357887__endTimestamp 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | ------- Summary: start ------- 18:19:17 INFO - PROCESS | 9116 | Number of tests: 6 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#0] dojo.html Cycles:165 Average:4694.79 Median:4810.83 stddev:486.30 (10.1%) stddev-sans-first:459.12 18:19:17 INFO - PROCESS | 9116 | Values: 2597.2 2620.3 2650.9 2654.5 2670.1 4626.1 4713.2 4730.7 4810.8 4847.7 4675.5 4718.5 4726.8 4726.8 4752.4 4315.5 4345.8 4367.8 4383.7 4432.4 4445.3 4445.9 4469.9 4486.7 4535.1 4273.1 4325.1 4344.4 4386.5 4398.1 4362.1 4376.1 4381.0 4383.9 4385.6 4287.7 4330.5 4351.2 4354.6 4360.8 4715.1 4720.2 4787.8 4791.1 4817.3 5071.3 5071.8 5073.7 5078.7 5131.7 4927.0 4981.8 5074.9 5075.4 5115.2 4700.7 4733.9 4739.8 4745.1 4807.2 5194.6 5195.5 5197.6 5201.7 5204.1 4859.3 4986.2 4991.9 4993.7 4995.1 4984.6 5077.0 5078.8 5105.1 5120.4 4977.7 5024.6 5027.6 5093.1 5155.7 5062.4 5071.0 5071.7 5078.9 5130.2 4274.8 4303.5 4308.5 4386.4 4397.4 3812.2 3812.7 3815.4 3850.4 3865.5 4343.5 4371.6 4377.1 4382.3 4437.7 4898.7 4955.2 5090.3 5126.0 5133.1 4336.3 4361.0 4362.3 4394.1 4415.6 4617.2 4630.5 4632.1 4638.9 4679.4 4670.0 4714.7 4769.2 4781.5 4820.0 5151.4 5154.4 5158.1 5205.8 5222.3 4801.4 4810.2 4812.3 4814.6 4871.1 4789.3 4820.6 4913.3 5068.0 5146.9 4943.3 4978.7 4982.9 5039.2 5044.0 4828.2 4836.0 4840.6 4844.3 4901.3 4754.3 4786.8 4822.4 4895.0 4937.4 4913.4 4966.4 4973.8 5012.8 5027.5 5033.9 5077.0 5083.6 5091.3 5146.9 4794.6 4835.5 4845.4 4846.2 4963.5 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#1] ext.html Cycles:180 Average:45456.02 Median:35323.97 stddev:80553.35 (228.0%) stddev-sans-first:80708.82 18:19:17 INFO - PROCESS | 9116 | Values: 568.3 570.4 577.2 577.6 578.6 27262.7 38200.0 39316.8 39362.8 43281.8 39535.0 39976.4 40035.8 40040.0 40091.2 7667.3 7786.4 7800.9 7813.1 7814.8 7571.7 7654.7 7666.0 7666.7 7668.2 7653.5 7716.6 7720.5 7734.8 7741.3 7661.0 7695.4 7710.2 7716.0 7723.6 7591.9 7644.6 7645.1 7646.9 7655.5 37745.0 37866.0 38042.4 38134.0 38137.0 3008.0 3008.7 3033.8 3042.1 3051.3 27394.5 27436.9 27879.4 27980.7 28674.3 3154.3 3170.6 3206.0 3209.4 3277.0 249208.0 249381.0 249724.0 250654.0 251092.0 51730.7 52085.7 52156.0 52183.7 52189.5 32142.8 33044.0 33128.8 55410.0 55586.7 14565.0 14956.8 14973.2 14974.2 15066.8 11378.9 11469.9 11478.8 11480.8 11487.0 17630.3 17705.3 17716.8 17786.4 17806.9 6612.3 6674.6 6732.2 6733.3 6835.7 758.3 841.2 898.9 908.3 908.3 26601.5 27795.4 28134.0 28362.4 28407.6 726.5 877.3 911.6 913.6 954.0 14408.8 14688.0 14712.4 14795.4 14932.8 363335.0 366179.0 366577.0 366594.0 366807.0 48671.8 49179.3 49291.3 49337.5 49375.5 1052.9 1074.5 1133.7 1136.9 1179.1 27417.3 28576.7 29322.7 29524.1 30071.4 41469.6 42057.2 42068.4 42103.6 42154.0 291013.0 292187.0 293476.0 293503.0 293907.0 25076.6 26181.3 26380.9 26487.1 26499.9 40517.2 41012.6 41058.2 41062.8 41552.8 33804.7 34108.4 34519.2 34617.3 34661.3 34671.7 35078.8 35270.6 35327.5 35397.0 34753.0 35005.2 35041.8 35320.5 35969.3 30495.8 32157.4 32263.7 32416.8 32746.3 24211.4 25041.1 25052.0 25061.1 25376.2 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#2] jquery.html Cycles:180 Average:23459.63 Median:45248.94 stddev:62628.24 (138.4%) stddev-sans-first:62779.87 18:19:17 INFO - PROCESS | 9116 | Values: 340.0 359.5 360.0 363.8 378.9 12183.6 13164.6 15278.6 16049.2 17398.3 16325.4 16434.3 16724.8 16969.7 17145.2 4780.0 4842.7 4984.3 5046.6 5048.0 4951.2 5066.4 5069.7 5071.2 5079.6 3612.4 3821.1 5442.3 5688.7 5703.4 5080.3 5787.3 5796.2 5796.6 5798.9 5110.6 5249.1 5255.5 5256.4 5259.6 11371.9 11465.7 11522.3 11716.8 11909.4 1821.5 1858.1 1926.2 1930.4 1957.8 8951.8 9099.0 9251.0 9503.5 9972.8 1643.6 1713.7 1737.1 1776.7 1801.9 62540.0 67110.3 67408.0 68135.6 71261.3 9333.6 9595.8 9835.9 9851.1 9918.0 13184.5 13363.9 13391.3 13406.1 13415.3 6810.7 6917.8 7117.6 7119.8 7149.1 6649.5 6711.7 6718.4 6880.1 6959.3 3695.8 3757.2 3918.5 3987.7 4047.5 1661.9 1712.6 1757.2 1842.0 1842.6 815.0 825.6 851.8 875.7 886.4 15569.5 16384.0 16473.8 16934.2 17318.6 810.7 814.6 828.4 834.8 896.8 6974.8 9261.6 9428.5 9522.3 9614.6 367826.0 375793.0 382316.0 383508.0 383726.0 75639.6 78441.6 79141.0 80036.0 80077.6 835.4 867.5 870.3 877.1 879.1 14712.5 14737.8 14780.5 14825.7 15461.7 36567.5 37823.8 37920.4 38107.0 38193.8 43795.3 46702.5 47000.5 47343.5 48102.8 7221.4 7379.9 7415.7 7543.4 7636.1 9228.3 10547.3 10991.2 11413.4 12089.2 9200.8 10055.9 10124.8 10223.9 10922.6 10243.3 10490.7 10594.7 10604.6 10716.4 10286.0 10338.2 10660.8 10835.8 11136.2 7426.9 7447.3 7487.9 7662.9 7751.7 10401.2 10730.9 10797.2 11033.7 11248.2 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#3] mootools.html Cycles:180 Average:4898.46 Median:4924.00 stddev:2718.53 (55.2%) stddev-sans-first:2712.83 18:19:17 INFO - PROCESS | 9116 | Values: 1316.1 1347.7 1366.5 1624.5 1664.9 7155.9 7647.8 7656.3 7663.3 7707.1 6504.0 6737.4 7142.8 7363.2 7386.9 2127.8 2154.2 2154.8 2156.0 2158.4 1746.5 2130.1 2147.9 2179.4 2186.4 1828.3 2055.8 2132.7 2133.1 2138.9 2064.5 2067.7 2071.9 2072.3 2085.9 2068.0 2073.7 2083.8 2084.2 2084.8 7415.7 7501.7 7508.1 7514.3 7527.9 4451.2 4616.1 4619.3 4630.7 4633.0 2760.1 2913.5 2916.1 2924.2 2926.6 870.0 924.8 925.5 928.0 938.9 10019.9 10036.1 10060.3 10068.9 10071.8 3770.3 3878.4 4019.0 4086.3 4091.8 8709.4 8710.0 8720.2 8721.7 8728.7 4509.2 4509.5 4509.7 4509.8 4511.9 4003.2 4137.4 4139.0 4140.6 4141.5 3456.0 3528.9 3574.1 3581.6 3587.1 1060.3 1167.7 1170.9 1181.5 1185.5 678.6 733.5 742.7 766.6 780.0 4784.6 4838.1 4861.4 4890.6 4937.7 795.7 849.4 869.4 882.9 950.0 3439.2 3478.7 3493.7 3583.1 3621.5 8988.6 9107.5 9252.1 9276.3 9295.0 8260.9 9050.9 9072.0 9199.7 9218.6 6513.8 6773.7 6788.7 6793.3 6796.8 4850.5 4910.3 4945.9 5045.8 5229.0 8329.5 8463.4 8528.3 8585.4 8674.3 8552.8 8657.0 8686.6 8695.6 8762.1 6500.6 6626.3 6627.3 6631.0 6633.2 7234.8 7241.9 7273.2 7282.0 7290.9 5148.7 6503.8 6587.8 6601.1 6727.0 6479.9 6524.6 6545.4 6563.2 6577.3 5510.2 5599.7 5607.8 5609.6 5611.4 4121.1 4145.8 4252.1 4256.0 4368.9 4235.6 4261.3 4300.4 4329.3 4417.1 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#4] prototype.html Cycles:180 Average:4066.14 Median:4385.36 stddev:1169.11 (26.7%) stddev-sans-first:1137.43 18:19:17 INFO - PROCESS | 9116 | Values: 285.1 287.9 295.7 296.8 302.4 4654.0 4806.4 4864.2 4952.2 4965.9 4657.0 4768.8 4783.4 4807.8 4840.9 3807.4 3810.2 3826.9 3831.4 3833.1 3559.4 3593.3 3825.7 3844.4 3847.7 3793.2 3812.2 3816.8 3820.8 3822.7 3779.6 3822.4 3828.4 3830.9 3831.8 3345.9 3346.5 3360.4 3363.6 3374.7 4580.4 4677.4 4722.6 4733.5 4753.1 4315.6 4318.8 4324.1 4328.9 4342.7 5019.5 5080.9 5097.5 5098.3 5111.7 3581.5 3632.5 3648.4 3695.7 3699.9 5258.5 5268.0 5280.7 5285.3 5321.6 3737.5 3740.4 3747.4 3751.8 3755.8 3783.9 3812.8 3817.7 3832.1 3837.6 4174.8 4255.6 4262.9 4290.0 4327.0 5023.6 5029.6 5043.8 5051.8 5057.6 1208.5 1244.7 1263.9 1282.6 1291.8 877.0 882.9 896.1 899.7 907.9 4340.2 4398.6 4398.9 4413.5 4420.5 5101.8 5103.7 5132.4 5136.7 5140.7 4342.0 4350.6 4356.3 4360.3 4372.1 2311.3 2416.7 2420.7 2423.7 2428.2 4257.7 4266.8 4286.2 4295.3 4310.4 4619.2 4634.7 4638.0 4650.6 4671.8 4062.4 4245.8 4250.7 4287.7 4307.4 4489.8 4505.1 4522.4 4522.9 4555.0 4449.6 4481.5 4489.0 4507.4 4556.0 4031.5 4252.5 4261.9 4268.3 4284.3 3342.3 3362.6 3369.9 3376.2 3392.7 4552.4 4600.5 4627.7 4638.7 5004.6 4864.4 5026.8 5069.2 5085.3 5100.8 5040.3 5040.7 5067.6 5069.6 5070.2 4763.6 4947.4 4981.7 4995.3 5024.7 5056.7 5085.4 5092.6 5095.2 5115.5 4976.4 4997.2 5025.6 5064.8 5085.5 18:19:17 INFO - PROCESS | 9116 | 18:19:17 INFO - PROCESS | 9116 | [#5] yui.html Cycles:180 Average:18058.65 Median:4087.27 stddev:26093.38 (638.4%) stddev-sans-first:26133.64 18:19:17 INFO - PROCESS | 9116 | Values: 598.4 600.3 614.1 645.5 699.2 12972.7 14237.2 14268.6 14318.1 14396.2 13395.3 13523.7 13527.3 13527.7 13534.1 607.5 624.9 741.4 748.5 764.3 771.4 773.6 774.1 774.3 775.4 800.5 807.2 808.5 809.1 809.3 645.8 661.9 783.8 797.1 803.6 590.0 590.1 590.2 590.3 590.6 12397.4 12429.4 12430.1 12434.6 12435.0 11785.8 11982.3 12287.0 12441.4 12753.4 10532.5 10559.6 10781.3 11347.9 11631.9 4080.2 4094.4 4165.1 4298.2 4305.5 63561.7 64250.0 66639.0 67270.7 67740.7 10077.1 10210.9 10239.5 10314.6 10424.1 18690.9 19673.4 19904.2 19933.4 19947.2 6453.9 6512.9 6546.5 6559.7 6727.8 3458.4 3480.5 3507.6 3528.5 3538.6 2752.6 2810.7 2814.2 2821.3 2847.9 470.6 471.9 475.7 481.6 485.5 557.9 567.0 572.4 586.9 587.9 7405.2 7612.5 7919.6 8003.8 8077.2 555.0 558.6 576.8 588.9 589.5 3565.3 3698.7 3753.7 3906.1 3945.0 100677.5 100862.0 103529.0 104795.0 105934.0 84750.6 85991.0 86748.1 87147.6 89958.6 74178.6 74886.6 75414.6 75833.0 77022.0 7096.7 7137.3 7648.6 7721.7 7725.7 37937.0 39854.6 40266.6 40375.6 42985.1 56418.0 59180.4 63411.4 63464.0 63711.0 11220.3 11319.8 11324.8 11511.7 11623.1 12473.3 12550.7 12821.6 13077.6 13641.2 12491.4 12522.8 12633.4 12717.7 13621.3 11789.6 11956.9 12221.3 13377.7 13491.0 12199.7 12333.7 12341.9 12404.7 13175.0 10208.9 10279.6 10309.3 10531.9 10634.8 9080.6 9357.8 9532.5 9738.5 10062.8 18:19:17 INFO - PROCESS | 9116 | -------- Summary: end -------- 18:19:17 INFO - PROCESS | 9116 | 18:19:18 INFO - TEST-INFO | 9116: exit 0 18:19:18 INFO - TEST-OK | dromaeo_css | took 1121042ms 18:19:18 INFO - TEST-START | kraken 18:19:18 INFO - operating with platform_type : linux_ 18:19:18 INFO - Initialising browser for kraken test... 18:19:18 INFO - TEST-INFO | started process 9425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcO8c01/profile http://localhost:53265/getInfo.html) 18:19:23 INFO - PROCESS | 9425 | __metrics Screen width/height:1600/1200 18:19:23 INFO - PROCESS | 9425 | colorDepth:24 18:19:23 INFO - PROCESS | 9425 | Browser inner width/height: 1024/697 18:19:23 INFO - PROCESS | 9425 | __metrics 18:19:23 INFO - PROCESS | 9425 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:19:23 INFO - PROCESS | 9425 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:19:24 INFO - PROCESS | 9425 | console.error: PushService: 18:19:24 INFO - PROCESS | 9425 | stateChangeProcessEnqueue: Error transitioning state 18:19:24 INFO - PROCESS | 9425 | UnknownError 18:19:24 INFO - TEST-INFO | 9425: exit 0 18:19:24 INFO - Browser initialized. 18:19:24 INFO - Running cycle 1/1 for kraken test... 18:19:24 INFO - TEST-INFO | started process 9509 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcO8c01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:37 INFO - PROCESS | 9509 | RSS: Main: 175120384 18:19:37 INFO - PROCESS | 9509 | 18:19:39 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53265/tests/kraken/driver.html?testName=audio-beat-detection) 18:19:40 INFO - PROCESS | 9509 | RSS: Main: 242413568 18:19:40 INFO - PROCESS | 9509 | 18:19:43 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53265/tests/kraken/driver.html?testName=audio-dft) 18:19:44 INFO - PROCESS | 9509 | RSS: Main: 218406912 18:19:44 INFO - PROCESS | 9509 | 18:19:47 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53265/tests/kraken/driver.html?testName=audio-fft) 18:19:48 INFO - PROCESS | 9509 | RSS: Main: 299323392 18:19:48 INFO - PROCESS | 9509 | 18:19:50 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53265/tests/kraken/driver.html?testName=audio-oscillator) 18:19:50 INFO - PROCESS | 9509 | RSS: Main: 209641472 18:19:50 INFO - PROCESS | 9509 | 18:19:53 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:19:53 INFO - PROCESS | 9509 | RSS: Main: 213983232 18:19:53 INFO - PROCESS | 9509 | 18:19:56 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53265/tests/kraken/driver.html?testName=imaging-darkroom) 18:19:57 INFO - PROCESS | 9509 | RSS: Main: 250335232 18:19:57 INFO - PROCESS | 9509 | 18:20:00 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53265/tests/kraken/driver.html?testName=imaging-desaturate) 18:20:01 INFO - PROCESS | 9509 | RSS: Main: 249434112 18:20:01 INFO - PROCESS | 9509 | 18:20:04 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53265/tests/kraken/driver.html?testName=json-parse-financial) 18:20:05 INFO - PROCESS | 9509 | RSS: Main: 249397248 18:20:05 INFO - PROCESS | 9509 | 18:20:06 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:20:07 INFO - PROCESS | 9509 | RSS: Main: 211177472 18:20:07 INFO - PROCESS | 9509 | 18:20:09 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:20:09 INFO - PROCESS | 9509 | RSS: Main: 219160576 18:20:09 INFO - PROCESS | 9509 | 18:20:12 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:20:12 INFO - PROCESS | 9509 | RSS: Main: 222793728 18:20:12 INFO - PROCESS | 9509 | 18:20:15 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:20:16 INFO - PROCESS | 9509 | RSS: Main: 223600640 18:20:16 INFO - PROCESS | 9509 | 18:20:19 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:20:19 INFO - PROCESS | 9509 | RSS: Main: 222154752 18:20:19 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | Cycle 1(1): loaded http://localhost:53265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53265/tests/kraken/driver.html?testName=ai-astar) 18:20:21 INFO - PROCESS | 9509 | RSS: Main: 273006592 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | __start_tp_report 18:20:21 INFO - PROCESS | 9509 | _x_x_mozilla_page_load 18:20:21 INFO - PROCESS | 9509 | _x_x_mozilla_page_load_details 18:20:21 INFO - PROCESS | 9509 | |i|pagename|runs| 18:20:21 INFO - PROCESS | 9509 | |0;ai-astar;113;118;114;117;115;116;115;115;115;114 18:20:21 INFO - PROCESS | 9509 | |1;audio-beat-detection;155;123;128;175;125;125;124;128;163;125 18:20:21 INFO - PROCESS | 9509 | |2;audio-dft;162;161;161;161;162;160;160;162;164;161 18:20:21 INFO - PROCESS | 9509 | |3;audio-fft;75;76;76;100;78;78;76;76;101;77 18:20:21 INFO - PROCESS | 9509 | |4;audio-oscillator;83;85;120;84;84;83;103;82;83;114 18:20:21 INFO - PROCESS | 9509 | |5;imaging-gaussian-blur;119;119;116;117;116;116;116;118;117;117 18:20:21 INFO - PROCESS | 9509 | |6;imaging-darkroom;147;147;147;146;147;147;146;147;147;146 18:20:21 INFO - PROCESS | 9509 | |7;imaging-desaturate;94;95;94;95;94;94;95;94;94;95 18:20:21 INFO - PROCESS | 9509 | |8;json-parse-financial;64;64;64;63;64;64;64;64;64;64 18:20:21 INFO - PROCESS | 9509 | |9;json-stringify-tinderbox;66;65;66;66;67;66;65;66;66;66 18:20:21 INFO - PROCESS | 9509 | |10;stanford-crypto-aes;84;83;83;83;84;88;83;91;83;82 18:20:21 INFO - PROCESS | 9509 | |11;stanford-crypto-ccm;135;134;132;144;135;134;133;133;175;144 18:20:21 INFO - PROCESS | 9509 | |12;stanford-crypto-pbkdf2;181;177;173;177;177;178;175;176;174;183 18:20:21 INFO - PROCESS | 9509 | |13;stanford-crypto-sha256-iterative;69;72;73;72;69;69;73;69;68;70 18:20:21 INFO - PROCESS | 9509 | __end_tp_report 18:20:21 INFO - PROCESS | 9509 | __start_cc_report 18:20:21 INFO - PROCESS | 9509 | _x_x_mozilla_cycle_collect,1924 18:20:21 INFO - PROCESS | 9509 | __end_cc_report 18:20:21 INFO - PROCESS | 9509 | __startTimestamp1475198421864__endTimestamp 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | ------- Summary: start ------- 18:20:21 INFO - PROCESS | 9509 | Number of tests: 14 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.00 stddev:1.48 (1.3%) stddev-sans-first:1.33 18:20:21 INFO - PROCESS | 9509 | Values: 113.0 118.0 114.0 117.0 115.0 116.0 115.0 115.0 115.0 114.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:128.00 stddev:19.44 (15.2%) stddev-sans-first:19.52 18:20:21 INFO - PROCESS | 9509 | Values: 155.0 123.0 128.0 175.0 125.0 125.0 124.0 128.0 163.0 125.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 18:20:21 INFO - PROCESS | 9509 | Values: 162.0 161.0 161.0 161.0 162.0 160.0 160.0 162.0 164.0 161.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:10.17 (13.4%) stddev-sans-first:10.52 18:20:21 INFO - PROCESS | 9509 | Values: 75.0 76.0 76.0 100.0 78.0 78.0 76.0 76.0 101.0 77.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#4] audio-oscillator Cycles:10 Average:92.10 Median:83.00 stddev:14.56 (17.5%) stddev-sans-first:15.07 18:20:21 INFO - PROCESS | 9509 | Values: 83.0 85.0 120.0 84.0 84.0 83.0 103.0 82.0 83.0 114.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:1.20 (1.0%) stddev-sans-first:1.05 18:20:21 INFO - PROCESS | 9509 | Values: 119.0 119.0 116.0 117.0 116.0 116.0 116.0 118.0 117.0 117.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 18:20:21 INFO - PROCESS | 9509 | Values: 147.0 147.0 147.0 146.0 147.0 147.0 146.0 147.0 147.0 146.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 18:20:21 INFO - PROCESS | 9509 | Values: 94.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 95.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 18:20:21 INFO - PROCESS | 9509 | Values: 64.0 64.0 64.0 63.0 64.0 64.0 64.0 64.0 64.0 64.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#9] json-stringify-tinderbox Cycles:10 Average:65.90 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:20:21 INFO - PROCESS | 9509 | Values: 66.0 65.0 66.0 66.0 67.0 66.0 65.0 66.0 66.0 66.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:2.84 (3.4%) stddev-sans-first:3.00 18:20:21 INFO - PROCESS | 9509 | Values: 84.0 83.0 83.0 83.0 84.0 88.0 83.0 91.0 83.0 82.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:135.00 stddev:13.08 (9.7%) stddev-sans-first:13.76 18:20:21 INFO - PROCESS | 9509 | Values: 135.0 134.0 132.0 144.0 135.0 134.0 133.0 133.0 175.0 144.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:177.00 stddev:3.03 (1.7%) stddev-sans-first:2.87 18:20:21 INFO - PROCESS | 9509 | Values: 181.0 177.0 173.0 177.0 177.0 178.0 175.0 176.0 174.0 183.0 18:20:21 INFO - PROCESS | 9509 | 18:20:21 INFO - PROCESS | 9509 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:1.90 (2.7%) stddev-sans-first:1.94 18:20:21 INFO - PROCESS | 9509 | Values: 69.0 72.0 73.0 72.0 69.0 69.0 73.0 69.0 68.0 70.0 18:20:21 INFO - PROCESS | 9509 | -------- Summary: end -------- 18:20:21 INFO - PROCESS | 9509 | 18:20:23 INFO - TEST-INFO | 9509: exit 0 18:20:23 INFO - TEST-OK | kraken | took 64345ms 18:20:23 INFO - SUITE-END | took 1185s 18:20:23 INFO - Completed test suite (00:19:45) 18:20:23 INFO - javascript benchmark 18:20:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2597.2467984284553, 2620.2532278300687, 2650.867456627168, 2654.4558365535067, 2670.078822806131, 4626.097910907374, 4713.198756211444, 4730.739809310491, 4810.831620893269, 4847.709137451755, 4675.485696573374, 4718.4573774016, 4726.787294571744, 4726.787294571744, 4752.358431611731, 4315.525292217853, 4345.8478953236645, 4367.803448844809, 4383.715058521186, 4432.3573081903105, 4445.333200019991, 4445.911081778362, 4469.888252793674, 4486.685931984763, 4535.138323718499, 4273.059926816096, 4325.134973005412, 4344.435223420966, 4386.539413361615, 4398.098389830077, 4362.105768317519, 4376.102898905717, 4381.036172042146, 4383.868483945486, 4385.605295523386, 4287.742735435884, 4330.523642399334, 4351.238533256667, 4354.586314300136, 4360.847370342023, 4715.104130215283, 4720.197566413718, 4787.832425865078, 4791.089692958327, 4817.277408388734, 5071.264666623356, 5071.847844564669, 5073.695578265316, 5078.7460626968505, 5131.692098474066, 4927.039227350632, 4981.825636102755, 5074.949250507485, 5075.390953085616, 5115.2327150927285, 4700.670953033323, 4733.881652958635, 4739.834105806281, 4745.074710431432, 4807.230843065093, 5194.636375453758, 5195.506426889439, 5197.610179236573, 5201.661891977085, 5204.063268611686, 4859.34398856157, 4986.152354099811, 4991.875203119951, 4993.650444468925, 4995.050940321327, 4984.576311013604, 5076.974615126975, 5078.822241221539, 5105.106606343875, 5120.436751957282, 4977.726225057657, 5024.62315326352, 5027.572656324181, 5093.1087059764395, 5155.690658560424, 5062.417821950523, 5070.974645126751, 5071.746412679351, 5078.873028174251, 5130.153524668388, 4274.84579163624, 4303.548127446636, 4308.547602501755, 4386.429764130643, 4397.362382454527, 3812.218495208476, 3812.714046446472, 3815.4086116651965, 3850.3839385698716, 3865.5168103987003, 4343.457067866517, 4371.5628437156665, 4377.124575084996, 4382.320740285261, 4437.733735975852, 4898.657094003457, 4955.231939049453, 5090.261912022736, 5126.0, 5133.050385678662, 4336.349547567827, 4360.978195109004, 4362.280223763044, 4394.12117576486, 4415.62467190286, 4617.215073437524, 4630.467496237909, 4632.096741135513, 4638.930416043762, 4679.3916790816975, 4669.976650116728, 4714.740689262124, 4769.189237829647, 4781.545753153445, 4819.975900120477, 5151.407588127413, 5154.407243167084, 5158.09733296679, 5205.765740541609, 5222.3472065991755, 4801.423829140525, 4810.158222311012, 4812.326274321527, 4814.638902082428, 4871.050145221648, 4789.329493870791, 4820.638452116035, 4913.336699545515, 5067.974660126823, 5146.948530514647, 4943.307936888767, 4978.651494395358, 4982.925256121233, 5039.193729003342, 5044.0, 4828.179209534317, 4835.95164048355, 4840.588549973291, 4844.32179494864, 4901.264810278344, 4754.263089221176, 4786.7845946931775, 4822.421309442749, 4894.951050489592, 4937.358143441474, 4913.361263035782, 4966.354373931365, 4973.751312434436, 5012.824551140839, 5027.472115427974, 5033.92449113271, 5077.0, 5083.644144909821, 5091.338126043591, 5146.871328216825, 4794.568488836021, 4835.492273311393, 4845.41854977405, 4846.224604063333, 4963.4540200577185], "value": 4663.929093728086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.3208565763914, 570.3953808962498, 577.2092233639913, 577.6129992904754, 578.560294176426, 27262.727372726265, 38200.0, 39316.80341598292, 39362.8031859841, 43281.78359108208, 39535.0, 39976.400353994715, 40035.799821000925, 40040.0, 40091.19817603654, 7667.2716091971315, 7786.416018798584, 7800.921990780104, 7813.062432508107, 7814.804629884242, 7571.697132114737, 7654.693812247531, 7665.961670191641, 7666.655000524962, 7668.233176682343, 7653.540787552764, 7716.575588342639, 7720.459567830254, 7734.806629834271, 7741.303282704555, 7660.961695191516, 7695.384369250474, 7710.1904300048245, 7716.035495563055, 7723.613819309012, 7591.8861217081785, 7644.617769111522, 7645.08259008917, 7646.923530764677, 7655.540667559964, 37745.0, 37866.0, 38042.42936355957, 38134.0, 38137.0, 3008.0, 3008.74425673818, 3033.7876348655604, 3042.087373787877, 3051.343961048385, 27394.45211095767, 27436.862815685792, 27879.442411151664, 27980.720192798017, 28674.283142921384, 3154.3375891062906, 3170.619525656936, 3206.0, 3209.406259841937, 3276.950845737316, 249208.0, 249381.0, 249723.9999999982, 250654.0, 251092.0, 51730.741346293784, 52085.739571302656, 52156.0, 52183.73908130435, 52189.47810521846, 32142.83928580342, 33044.0, 33128.83435582807, 55410.0, 55586.7164574555, 14565.0, 14956.775648365283, 14973.176475293963, 14974.176420296988, 15066.849331506544, 11378.886211137782, 11469.942650286695, 11478.770424591461, 11480.827787583194, 11487.0, 17630.294788208328, 17705.29178832858, 17716.82283177152, 17786.37747678825, 17806.91096544535, 6612.2726500084955, 6674.599524028574, 6732.225794033651, 6733.293004234582, 6835.658217089225, 758.3402439877342, 841.1798496465897, 898.8606765951288, 908.2733812949614, 908.318760929303, 26601.46797064048, 27795.383431136008, 28134.0, 28362.43275134486, 28407.57388639172, 726.4551586310267, 877.3156937588691, 911.6307895302413, 913.5660561233494, 953.9856902146473, 14408.783868241984, 14688.0, 14712.411503539739, 14795.408183672747, 14932.776008359884, 363335.0, 366179.0, 366577.0, 366594.0, 366807.0, 48671.75664121657, 49179.262311065366, 49291.26063109056, 49337.50662493329, 49375.50624493709, 1052.8631277933819, 1074.5272080284649, 1133.7222380516826, 1136.8749437561862, 1179.0604455670546, 27417.31456713598, 28576.714232858238, 29322.680479378905, 29524.114276571738, 30071.398572028873, 41469.585304146574, 42057.158856823306, 42068.36897446479, 42103.57896421119, 42154.0, 291013.0, 292187.0, 293476.0, 293503.0, 293907.0, 25076.62385064262, 26181.34546636273, 26380.8680956594, 26487.072952447343, 26499.867500663146, 40517.1896562073, 41012.58987410088, 41058.1788364237, 41062.79468602638, 41552.79223603863, 33804.66195338113, 34108.37211860015, 34519.20288768183, 34617.30765384628, 34661.30677386488, 34671.65328346684, 35078.824605876805, 35270.64729352674, 35327.47008794819, 35397.0, 34753.0, 35005.199584033726, 35041.824790875886, 35320.47019294764, 35969.28061438809, 30495.847520762254, 32157.35685286328, 32263.677363227005, 32416.83791581027, 32746.34507309888, 24211.39471513226, 25041.12356067529, 25052.0, 25061.05112378775, 25376.238712838644], "value": 17037.634181169095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.9762016658835, 359.46978207144457, 359.95320608320895, 363.8290003698262, 378.9450529673197, 12183.634490965274, 13164.605061848148, 15278.618034549128, 16049.197540122976, 17398.30406783727, 16325.428609998653, 16434.342626294932, 16724.83275167248, 16969.745453818203, 17145.228464719086, 4779.952200478003, 4842.685225460343, 4984.327115839352, 5046.621503387242, 5048.040872234264, 4951.207806750921, 5066.36670416198, 5069.670471419365, 5071.1632580624155, 5079.593632509409, 3612.422012478004, 3821.12114213731, 5442.25847728727, 5688.715564221793, 5703.372629010805, 5080.314157588715, 5787.276590426197, 5796.188533605298, 5796.565257605696, 5798.942010579883, 5110.6422550421485, 5249.1076516991825, 5255.500727430888, 5256.3967989763105, 5259.579233661298, 11371.943140284246, 11465.656030319104, 11522.251053681484, 11716.765664686744, 11909.404529773477, 1821.5355084453513, 1858.1173942377397, 1926.1621194780175, 1930.4112245765036, 1957.8237958583654, 8951.820963580692, 9098.95362033368, 9250.953745231298, 9503.477308748015, 9972.750681232954, 1643.5644554193148, 1713.6658351621438, 1737.148797089417, 1776.7157254839165, 1801.927922883083, 62540.0, 67110.3288967109, 67408.0, 68135.60329792276, 71261.2873871265, 9333.626654933862, 9595.808083838285, 9835.901640983642, 9851.113399794052, 9917.950410248048, 13184.538541151014, 13363.933180334036, 13391.263480508665, 13406.128601640861, 13415.329233538285, 6810.727570897108, 6917.827054323682, 7117.572945623279, 7119.75080872167, 7149.106361704787, 6649.534532582769, 6711.664416779142, 6718.361755633207, 6880.071190389233, 6959.26927672587, 3695.7782533048103, 3757.2485502898985, 3918.5305510433586, 3987.7009224308295, 4047.4738284022887, 1661.8836681432183, 1712.5804177976474, 1757.2180379731153, 1841.992228470973, 1842.6222624361974, 815.0260438775724, 825.5913322905201, 851.8211175653058, 875.7066382761719, 886.3529623374973, 15569.532914012598, 16384.0, 16473.752893706605, 16934.237959291862, 17318.567035824206, 810.6554714246539, 814.5601375257413, 828.3952714518435, 834.7746108550779, 896.8430524658289, 6974.755883544051, 9261.629534818532, 9428.481433521221, 9522.285828562746, 9614.634877792856, 367826.0, 375793.0, 382316.0, 383508.0, 383726.0, 75639.62180189064, 78441.60779196068, 79141.0, 80036.0, 80077.59961200156, 835.4043776387193, 867.4795122926193, 870.299408975768, 877.1141147441003, 879.1252703559999, 14712.485063022743, 14737.778933315794, 14780.48268310604, 14825.703485930006, 15461.690766184389, 36567.45148822717, 37823.81088094542, 37920.43119353267, 38107.0, 38193.80903095467, 43795.34306985334, 46702.53297466982, 47000.529994700984, 47343.526564735286, 48102.75948620235, 7221.4222862171955, 7379.852402952019, 7415.665180267603, 7543.3965282778345, 7636.121845987783, 9228.261739060725, 10547.314424562379, 10991.175661825235, 11413.429328533706, 12089.214201076902, 9200.815983680423, 10055.89944100579, 10124.797504050026, 10223.948880255552, 10922.563097476012, 10243.334183278062, 10490.685279441628, 10594.682159535227, 10604.628837990633, 10716.410597417216, 10286.0, 10338.224633152695, 10660.786784264426, 10835.783284334428, 11136.164787640797, 7426.888596670946, 7447.329740323397, 7487.925120748723, 7662.923370766444, 7751.651175696994, 10401.167906567607, 10730.946345268223, 10797.24419290642, 11033.668989930315, 11248.156388270749], "value": 7863.290383923773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.0787448785852, 1347.6967682271486, 1366.5353779714894, 1624.5126462061378, 1664.875134364923, 7155.928440715591, 7647.847043059142, 7656.272654097866, 7663.310302072812, 7707.075150981883, 6504.0, 6737.359950804678, 7142.821429464266, 7363.153237377704, 7386.852262954738, 2127.7872212778716, 2154.1814110638015, 2154.806067453929, 2156.0272350799, 2158.384860314811, 1746.5109769264627, 2130.0521268035654, 2147.914083436661, 2179.3788770200495, 2186.431527802767, 1828.2961059991867, 2055.825254853339, 2132.6694362373855, 2133.0614529606923, 2138.893055347234, 2064.4735592423986, 2067.7415323084615, 2071.927482538119, 2072.336852207302, 2085.9061342239634, 2068.048697599106, 2073.7200477935435, 2083.7603675577197, 2084.249670118756, 2084.7602525709435, 7415.740449084256, 7501.737439189602, 7508.061492313461, 7514.286142816479, 7527.924720752777, 4451.1765323415275, 4616.053708989649, 4619.284010978304, 4630.675852690313, 4633.027064316498, 2760.0753747494614, 2913.490139225627, 2916.125162451256, 2924.166612515423, 2926.619539459878, 869.9782505437352, 924.7549399409162, 925.5187302602609, 928.0, 938.938968967015, 10019.949900250525, 10036.14692751117, 10060.346077504939, 10068.899311006942, 10071.798564028752, 3770.264798364293, 3878.398848178537, 4019.0, 4086.264472394938, 4091.7954102294766, 8709.433886797338, 8709.956450217709, 8720.171583699539, 8721.738347849574, 8728.694495692747, 4509.210888094571, 4509.549045095464, 4509.729416235036, 4509.751963641966, 4511.909761804745, 4003.179348233605, 4137.379393091001, 4139.0, 4140.565240649748, 4141.461609990682, 3456.0, 3528.9117772055392, 3574.12433953683, 3581.5881173664843, 3587.121155316964, 1060.2684147937898, 1167.6613782003294, 1170.9063274937985, 1181.5451051345294, 1185.508014174108, 678.5792808458788, 733.4719002318323, 742.7177672484421, 766.5515673331053, 779.9532028078333, 4784.593309568726, 4838.080764654705, 4861.416630004422, 4890.633202509826, 4937.728424936591, 795.7016118955391, 849.422392772908, 869.4001139213922, 882.9381943264038, 949.9572519236651, 3439.226174110855, 3478.652134786552, 3493.737969652286, 3583.1400463889, 3621.4748861414946, 8988.55057247135, 9107.544622768835, 9252.074792520829, 9276.257899368034, 9295.0, 8260.917390826135, 9050.86423703645, 9071.954640226888, 9199.678011269574, 9218.649244442624, 6513.804585862432, 6773.695183716744, 6788.660566971632, 6793.320667933167, 6796.796096117125, 4850.513275220477, 4910.263460480956, 4945.901081978413, 5045.798168073383, 5229.032628963654, 8329.50022998622, 8463.36524760633, 8528.317734581095, 8585.35222789193, 8674.262687671619, 8552.828943421222, 8657.047724750399, 8686.609102590524, 8695.565221739014, 8762.07998160184, 6500.642464664489, 6626.337366263335, 6627.3372662733345, 6631.038499417654, 6633.2040155181685, 7234.819129521804, 7241.891371629325, 7273.199948005821, 7281.963590182015, 7290.854182916418, 5148.716820574904, 6503.772367967098, 6587.835304117435, 6601.075849381186, 6726.966365168339, 6479.935200647933, 6524.608523488606, 6545.443637290884, 6563.212414510289, 6577.3422657735755, 5510.173473978935, 5599.664020158803, 5607.775688972395, 5609.607327487037, 5611.382747897646, 4121.093359460914, 4145.813438395219, 4252.128313695747, 4255.978565144405, 4368.912621747484, 4235.597618226264, 4261.254280500838, 4300.37644541546, 4329.307310830378, 4417.1386579616665], "value": 3962.9786917067063, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.08061501657147, 287.9064304101167, 295.6998646373931, 296.78186532898314, 302.3771031674749, 4653.9534604653945, 4806.423229212491, 4864.221724524077, 4952.232403977389, 4965.925511117332, 4656.9534304656945, 4768.809247630091, 4783.4453802514145, 4807.788417328034, 4840.903181936368, 3807.409851473012, 3810.1998580298173, 3826.8660596879113, 3831.4252862070703, 3833.13754405258, 3559.394902866519, 3593.2633810068874, 3825.674817640504, 3844.442555829403, 3847.6537111659945, 3793.222389410178, 3812.2184952084895, 3816.751911125768, 3820.8280627371696, 3822.73240873139, 3779.6031416701126, 3822.4266360045935, 3828.444875493066, 3830.942535861964, 3831.750936189139, 3345.866165353383, 3346.5482159908343, 3360.4119279126057, 3363.5795525559306, 3374.696277335027, 4580.381648477446, 4677.368555245032, 4722.6221902247735, 4733.4793172751315, 4753.073150735608, 4315.611594956437, 4318.784060796979, 4324.070324880135, 4328.935065974013, 4342.6742994275555, 5019.494151754496, 5080.949190508085, 5097.49025097491, 5098.311727916721, 5111.667741596784, 3581.4627805829073, 3632.4551317302353, 3648.3980143276326, 3695.6858667013335, 3699.9445008324897, 5258.475931552838, 5267.973660131714, 5280.656757310801, 5285.286486324374, 5321.574274058066, 3737.5328083989502, 3740.36413809653, 3747.362948298796, 3751.81817727415, 3755.830987605564, 3783.9243215136094, 3812.7712337259854, 3817.7019813263623, 3832.080300727805, 3837.6354246346555, 4174.83300667976, 4255.638270746959, 4262.914741705148, 4290.0, 4326.956730432718, 5023.572996295283, 5029.59763218949, 5043.798248070036, 5051.797928082836, 5057.595392368602, 1208.5045131496045, 1244.7012716948047, 1263.9368031598567, 1282.5891519328663, 1291.793313069923, 877.0133599700337, 882.885224920769, 896.090468174803, 899.6864577716476, 907.9409838360485, 4340.175366680321, 4398.626116780046, 4398.890027749332, 4413.514513403523, 4420.497031009408, 5101.775573862246, 5103.668261562986, 5132.358455193101, 5136.717480538607, 5140.717260550631, 4341.978290108593, 4350.58669426404, 4356.259435895905, 4360.302351623725, 4372.103718737651, 2311.2719493359537, 2416.673749043892, 2420.7337192908767, 2423.7333893271725, 2428.1865575032207, 4257.659387249013, 4266.786660667016, 4286.171127056128, 4295.334223195409, 4310.418093557392, 4619.175425706894, 4634.72191668501, 4638.0, 4650.6048185544205, 4671.789769460383, 4062.4000639590686, 4245.80893859777, 4250.744955302692, 4287.742735435884, 4307.353896915488, 4489.765314538528, 4505.121501307213, 4522.4346956630425, 4522.914500519917, 4554.977225113986, 4449.621782148553, 4481.498697936097, 4489.0, 4507.3689683445, 4556.0, 4031.5162180538523, 4252.468441444819, 4261.850835220753, 4268.2743933530755, 4284.258074832048, 3342.298117395374, 3362.5796775403073, 3369.871093183811, 3376.2403459221478, 3392.728581713506, 4552.408186935677, 4600.539946005372, 4627.722336659676, 4638.652101092499, 5004.599632029502, 4864.391951006124, 5026.824061157842, 5069.1635880079375, 5085.288059671575, 5100.795968161232, 5040.320565986924, 5040.672356296829, 5067.594592432522, 5069.569086627531, 5070.2141168119, 4763.618910487222, 4947.431045429822, 4981.676191047571, 4995.275685025578, 5024.698518088926, 5056.696598204119, 5085.440191865783, 5092.567131793839, 5095.210242412432, 5115.462876397924, 4976.402831660224, 4997.225430058346, 5025.597952163745, 5064.797408103635, 5085.4914508548845], "value": 3698.7195100797976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.4494265275946, 600.2556829531381, 614.1034090228267, 645.4900628503485, 699.2413231643667, 12972.675683107927, 14237.216953067576, 14268.643283917907, 14318.140911545315, 14396.208208548538, 13395.263260520667, 13523.729525409486, 13527.323633818294, 13527.661808454794, 13534.120282181651, 607.5413063137347, 624.8812725582147, 741.3772431157827, 748.4798065344575, 764.2510339866934, 771.4098714483424, 773.5822655765879, 774.1329710723998, 774.3031271855347, 775.3758224629171, 800.4636893281486, 807.2331285278979, 808.4785313472806, 809.0938149272823, 809.324214281076, 645.7868903261935, 661.8874791285493, 783.7962129846225, 797.1231645190302, 803.5500119932827, 589.9911501327483, 590.1295589006224, 590.2268028882178, 590.2828063902336, 590.5718354193176, 12397.380130993415, 12429.440675169639, 12430.067744919077, 12434.564790232389, 12435.0, 11785.764284714343, 11982.281063136152, 12287.0, 12441.440135193847, 12753.362331883369, 10532.473376331229, 10559.577616895314, 10781.299215550964, 11347.886521134766, 11631.941840290829, 4080.1843179785005, 4094.3653733671326, 4165.10450253197, 4298.183345164409, 4305.461817272841, 63561.68219158875, 64250.0, 66639.0, 67270.66364668195, 67740.6612966937, 10077.09306162449, 10210.897891021143, 10239.539220735085, 10314.587416503256, 10424.113950314157, 18690.906545467187, 19673.409797706092, 19904.203831846855, 19933.401997940084, 19947.20211191536, 6453.870922581522, 6512.934870651233, 6546.541742078009, 6559.7376104955265, 6727.798166055026, 3458.4466485362223, 3480.512728218001, 3507.596626387947, 3528.541289632383, 3538.575370955502, 2752.587111933226, 2810.6627204735564, 2814.2120206341847, 2821.2664707175873, 2847.886084556636, 470.5741304119777, 471.92921061840315, 475.6717864673364, 481.59786578207587, 485.4587135344137, 557.9246801681721, 566.963147395418, 572.3860788587824, 586.9119632055226, 587.8883012227749, 7405.2224516425, 7612.505187162817, 7919.564423956623, 8003.759887203393, 8077.151899050515, 554.9833504994857, 558.5503669546055, 576.8327185116269, 588.9234399528034, 589.548995018811, 3565.2512972276113, 3698.6856117230336, 3753.7184711146774, 3906.140649057203, 3944.980275098606, 100677.49661251646, 100862.0, 103529.0, 104795.0, 105934.0, 84750.57624711837, 85991.0, 86748.132518674, 87147.56426217829, 89958.55020724985, 74178.62910685412, 74886.62556687182, 75414.62292688501, 75833.0, 77022.0, 7096.680649370687, 7137.321954414322, 7648.579328137006, 7721.729739459092, 7725.690972361042, 37937.0, 39854.60145398509, 40266.59733402746, 40375.55868854482, 42985.140297194506, 56418.0, 59180.40819591921, 63411.36588634054, 63464.0, 63711.0, 11220.270682405617, 11319.830202546804, 11324.773504529698, 11511.663132384207, 11623.070154387799, 12473.313967731861, 12550.686232844251, 12821.615351539469, 13077.607671769862, 13641.249731264876, 12491.437885295365, 12522.81033301835, 12633.368331583568, 12717.692595829692, 13621.25083120438, 11789.646310610695, 11956.880431195925, 12221.327826969246, 13377.66555836073, 13491.0, 12199.738676391205, 12333.691657708629, 12341.876581234432, 12404.689882753004, 13175.0, 10208.948955255473, 10279.640212592523, 10309.278350515391, 10531.94734026325, 10634.840477393, 9080.63677452921, 9357.812843743222, 9532.52337383142, 9738.513074346396, 10062.78618703884], "value": 6542.839315837029, "unit": "score"}], "name": "dromaeo_css", "value": 6255.7002750976735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 118.0, 114.0, 117.0, 115.0, 116.0, 115.0, 115.0, 115.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 128.0, 175.0, 125.0, 125.0, 124.0, 128.0, 163.0, 125.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 160.0, 160.0, 162.0, 164.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 100.0, 78.0, 78.0, 76.0, 76.0, 101.0, 77.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 120.0, 84.0, 84.0, 83.0, 103.0, 82.0, 83.0, 114.0], "value": 92.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 119.0, 116.0, 117.0, 116.0, 116.0, 116.0, 118.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 83.0, 84.0, 88.0, 83.0, 91.0, 83.0, 82.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 132.0, 144.0, 135.0, 134.0, 133.0, 133.0, 175.0, 144.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 177.0, 173.0, 177.0, 177.0, 178.0, 175.0, 176.0, 174.0, 183.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 73.0, 72.0, 69.0, 69.0, 73.0, 69.0, 68.0, 70.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1546.9000000000003, "alertThreshold": 2.0}]} 18:20:23 INFO - Return code: 0 18:20:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:20:23 INFO - # TBPL SUCCESS # 18:20:23 INFO - Running post-action listener: _resource_record_post_action 18:20:23 INFO - [mozharness: 2016-09-30 01:20:23.689990Z] Finished run-tests step (success) 18:20:23 INFO - Running post-run listener: _resource_record_post_run 18:20:23 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 399945728; Read time: 8; Write time: 245696 18:20:23 INFO - TinderboxPrint: CPU usage
13.1% 18:20:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:20:23 INFO - TinderboxPrint: I/O write bytes / time
399,945,728 / 245,696 18:20:23 INFO - TinderboxPrint: CPU idle
8,336.3 (86.9%) 18:20:23 INFO - TinderboxPrint: CPU user
1,205.0 (12.6%) 18:20:23 INFO - TinderboxPrint: Swap in / out
0 / 0 18:20:23 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106663936; Read time: 0; Write time: 80096 18:20:23 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 293281792; Read time: 0; Write time: 165600 18:20:24 INFO - Running post-run listener: _upload_blobber_files 18:20:24 INFO - Blob upload gear active. 18:20:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:20:24 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 18:20:24 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:20:24 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:20:24 INFO - (blobuploader) - INFO - Open directory for files ... 18:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:20:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:28 INFO - (blobuploader) - INFO - Done attempting. 18:20:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:20:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:20:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:20:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:31 INFO - (blobuploader) - INFO - Done attempting. 18:20:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:20:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:20:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:20:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:20:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:20:32 INFO - (blobuploader) - INFO - Done attempting. 18:20:32 INFO - (blobuploader) - INFO - Iteration through files over. 18:20:32 INFO - Return code: 0 18:20:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:20:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:20:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fe071fbdd2e6918903214639bf49f3dcf936b3e53d4510233cb16b52642f55c67da3c70ccea8fd1ef893a09b8a8646ed15a5c9ce345f3290a18e46d2b6d6fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b1e1d521dea2a8683aa709280eb84d15cfd7f4af3023e3603c3915d6833fff90160c8632b79fa79e15950acb6c07e9ce923474a23e2d153379491ee4920d2837", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e3e434074e37713308e720ab1e4b354ccaef2a0fad8470111fd1776c356667eaa62bd289ccdd529038ad438b54c55d7a2d6a51a04450273a849ecdbaee10827"} 18:20:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:20:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:20:32 INFO - Contents: 18:20:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fe071fbdd2e6918903214639bf49f3dcf936b3e53d4510233cb16b52642f55c67da3c70ccea8fd1ef893a09b8a8646ed15a5c9ce345f3290a18e46d2b6d6fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b1e1d521dea2a8683aa709280eb84d15cfd7f4af3023e3603c3915d6833fff90160c8632b79fa79e15950acb6c07e9ce923474a23e2d153379491ee4920d2837", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e3e434074e37713308e720ab1e4b354ccaef2a0fad8470111fd1776c356667eaa62bd289ccdd529038ad438b54c55d7a2d6a51a04450273a849ecdbaee10827"} 18:20:32 INFO - Running post-run listener: copy_logs_to_upload_dir 18:20:32 INFO - Copying logs to upload dir... 18:20:32 INFO - mkdir: /builds/slave/test/build/upload/logs 18:20:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.108110 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-09-29 18:20:32.218096) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 18:20:32.224132) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 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-Non-PGO/sha512/c2fe071fbdd2e6918903214639bf49f3dcf936b3e53d4510233cb16b52642f55c67da3c70ccea8fd1ef893a09b8a8646ed15a5c9ce345f3290a18e46d2b6d6fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b1e1d521dea2a8683aa709280eb84d15cfd7f4af3023e3603c3915d6833fff90160c8632b79fa79e15950acb6c07e9ce923474a23e2d153379491ee4920d2837", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e3e434074e37713308e720ab1e4b354ccaef2a0fad8470111fd1776c356667eaa62bd289ccdd529038ad438b54c55d7a2d6a51a04450273a849ecdbaee10827"} build_url:https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/eegzERE9S82tE4fSihrUew/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-Non-PGO/sha512/c2fe071fbdd2e6918903214639bf49f3dcf936b3e53d4510233cb16b52642f55c67da3c70ccea8fd1ef893a09b8a8646ed15a5c9ce345f3290a18e46d2b6d6fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b1e1d521dea2a8683aa709280eb84d15cfd7f4af3023e3603c3915d6833fff90160c8632b79fa79e15950acb6c07e9ce923474a23e2d153379491ee4920d2837", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6e3e434074e37713308e720ab1e4b354ccaef2a0fad8470111fd1776c356667eaa62bd289ccdd529038ad438b54c55d7a2d6a51a04450273a849ecdbaee10827"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 18:20:32.249418) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:20:32.249754) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1475197180.806415-480944277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 18:20:32.332534) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 18:20:32.332855) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 18:20:32.333222) ========= ========= Total master_lag: 0.15 =========