builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1476758770.16 results: success (0) buildid: 20161017163001 builduid: 88fa24ac0be74a7d9257b31064067dc9 revision: 56b3f2c6f53e72698fea6c25130efceef2a26548 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.161482) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.162269) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.162758) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.178077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.178455) ========= 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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-17 19:46:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-10-17 19:46:10 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.336585) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.336918) ========= 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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014574 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:10.387838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 19:46:10.388432) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56b3f2c6f53e72698fea6c25130efceef2a26548 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 56b3f2c6f53e72698fea6c25130efceef2a26548 --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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-17 19:46:11,008 truncating revision to first 12 chars 2016-10-17 19:46:11,008 Setting DEBUG logging. 2016-10-17 19:46:11,008 attempt 1/10 2016-10-17 19:46:11,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56b3f2c6f53e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-17 19:46:12,301 unpacking tar archive at: mozilla-central-56b3f2c6f53e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.603980 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-17 19:46:12.007023) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:12.007367) ========= script_repo_revision: 56b3f2c6f53e72698fea6c25130efceef2a26548 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:12.007767) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:12.008046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-17 19:46:12.016531) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-10-17 19:46:12.016894) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:46:12 INFO - MultiFileLogger online at 20161017 19:46:12 in /builds/slave/test 19:46:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:46:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:46:12 INFO - {'append_to_log': False, 19:46:12 INFO - 'base_work_dir': '/builds/slave/test', 19:46:12 INFO - 'blob_upload_branch': 'Firefox', 19:46:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:46:12 INFO - 'branch': 'Firefox', 19:46:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:46:12 INFO - 'config_files': ('talos/linux_config.py',), 19:46:12 INFO - 'default_actions': ('clobber', 19:46:12 INFO - 'read-buildbot-config', 19:46:12 INFO - 'download-and-extract', 19:46:12 INFO - 'populate-webroot', 19:46:12 INFO - 'create-virtualenv', 19:46:12 INFO - 'install', 19:46:12 INFO - 'run-tests'), 19:46:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:46:12 INFO - 'download_minidump_stackwalk': True, 19:46:12 INFO - 'download_symbols': 'ondemand', 19:46:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:46:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:46:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:46:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:46:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:46:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:46:12 INFO - 'installer_path': 'installer.exe', 19:46:12 INFO - 'log_level': 'info', 19:46:12 INFO - 'log_name': 'talos', 19:46:12 INFO - 'log_to_console': True, 19:46:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:46:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:46:12 INFO - 'opt_config_files': (), 19:46:12 INFO - 'pip_index': False, 19:46:12 INFO - 'sps_profile': False, 19:46:12 INFO - 'sps_profile_interval': 0, 19:46:12 INFO - 'suite': 'dromaeojs', 19:46:12 INFO - 'system_bits': '32', 19:46:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:46:12 INFO - 'title': 'talos-linux64-ix-010', 19:46:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:46:12 INFO - 'use_talos_json': True, 19:46:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:46:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:46:12 INFO - 'work_dir': 'build'} 19:46:12 INFO - [mozharness: 2016-10-18 02:46:12.736027Z] Running clobber step. 19:46:12 INFO - Running pre-action listener: _resource_record_pre_action 19:46:12 INFO - Running main action method: clobber 19:46:12 INFO - rmtree: /builds/slave/test/build 19:46:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:46:13 INFO - Running post-action listener: _resource_record_post_action 19:46:13 INFO - [mozharness: 2016-10-18 02:46:13.723761Z] Finished clobber step (success) 19:46:13 INFO - [mozharness: 2016-10-18 02:46:13.723862Z] Running read-buildbot-config step. 19:46:13 INFO - Running pre-action listener: _resource_record_pre_action 19:46:13 INFO - Running main action method: read_buildbot_config 19:46:13 INFO - Using buildbot properties: 19:46:13 INFO - { 19:46:13 INFO - "project": "", 19:46:13 INFO - "product": "firefox", 19:46:13 INFO - "script_repo_revision": "production", 19:46:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:46:13 INFO - "repository": "", 19:46:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:46:13 INFO - "buildid": "20161017163001", 19:46:13 INFO - "pgo_build": "True", 19:46:13 INFO - "basedir": "/builds/slave/test", 19:46:13 INFO - "buildnumber": 41, 19:46:13 INFO - "slavename": "talos-linux64-ix-010", 19:46:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:46:13 INFO - "platform": "ubuntu64_hw", 19:46:13 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:46:13 INFO - "branch": "mozilla-central", 19:46:13 INFO - "repo_path": "mozilla-central", 19:46:13 INFO - "revision": "56b3f2c6f53e72698fea6c25130efceef2a26548", 19:46:13 INFO - "stage_platform": "linux64-pgo", 19:46:13 INFO - "builduid": "88fa24ac0be74a7d9257b31064067dc9", 19:46:13 INFO - "slavebuilddir": "test" 19:46:13 INFO - } 19:46:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 19:46:13 INFO - Running post-action listener: _resource_record_post_action 19:46:13 INFO - [mozharness: 2016-10-18 02:46:13.726306Z] Finished read-buildbot-config step (success) 19:46:13 INFO - [mozharness: 2016-10-18 02:46:13.726396Z] Running download-and-extract step. 19:46:13 INFO - Running pre-action listener: _resource_record_pre_action 19:46:13 INFO - Running main action method: download_and_extract 19:46:13 INFO - mkdir: /builds/slave/test/build/tests 19:46: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')]} 19:46:13 INFO - trying https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 19:46:13 INFO - Downloading https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/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 19:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/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 19:46:14 INFO - Downloaded 1361 bytes. 19:46:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 19:46:14 INFO - Using the following test package requirements: 19:46:14 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 19:46:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:46:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:46:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'jsshell-linux-x86_64.zip'], 19:46:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:46:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 19:46:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:46:14 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:46:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:46:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 19:46:14 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:46:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:46:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 19:46:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:46:14 INFO - Fetch https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:46:14 INFO - Http code: 200 19:46:14 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 19:46:14 INFO - x-amz-cf-id: H52yENHcOv-TRffm1YTJBBCdeu6CF1xgdJL4Fd6piMmbD39KekHtNw== 19:46:14 INFO - x-amz-version-id: q6GXbm38IUaRG9Hhc61LoaIubjDQsMUo 19:46:15 INFO - Expected file size: 22089339 19:46:15 INFO - Obtained file size: 22089339 19:46:19 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 19:46:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 19:46:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:46:19 INFO - Fetch https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:46:20 INFO - Http code: 200 19:46:20 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 19:46:20 INFO - x-amz-cf-id: teUNOV-ch7ywLu45fXqvtWtQ2oqNdDoMuYW84GDXL0Y0qJObWmgXXw== 19:46:20 INFO - x-amz-version-id: q6GXbm38IUaRG9Hhc61LoaIubjDQsMUo 19:46:20 INFO - Expected file size: 22089339 19:46:20 INFO - Obtained file size: 22089339 19:46:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 19:46:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:46:24 INFO - Fetch https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:46:25 INFO - Http code: 200 19:46:25 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 19:46:25 INFO - x-amz-cf-id: 9kxRqIn6qyjg-t7BniNPtPuRxKyWvomxRxepIxEwQzvhsuG5T-Wd3Q== 19:46:25 INFO - x-amz-version-id: vxiB2ox3h0gXwbpZZs12LdORnyXTe5ET 19:46:25 INFO - Expected file size: 14409028 19:46:25 INFO - Obtained file size: 14409028 19:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:25 INFO - trying https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 19:46:25 INFO - Downloading https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/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 19:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/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 19:46:26 INFO - Downloaded 59496287 bytes. 19:46:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 19:46:26 INFO - mkdir: /builds/slave/test/properties 19:46:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:46:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:46:26 INFO - Contents: 19:46:26 INFO - build_url:https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 19:46:27 INFO - Running post-action listener: _resource_record_post_action 19:46:27 INFO - Running post-action listener: set_extra_try_arguments 19:46:27 INFO - [mozharness: 2016-10-18 02:46:27.681148Z] Finished download-and-extract step (success) 19:46:27 INFO - [mozharness: 2016-10-18 02:46:27.681381Z] Running populate-webroot step. 19:46:27 INFO - Running pre-action listener: _resource_record_pre_action 19:46:27 INFO - Running main action method: populate_webroot 19:46:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:46:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:46:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:46:27 INFO - '--tppagecycles', 19:46:27 INFO - '7'], 19:46:27 INFO - 'tests': ['tsvgm']}}, 19:46:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:46:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:46:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:46:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:46:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:46:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:46:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['damp', 'tps']}, 19:46:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'tests': ['damp', 'tps']}, 19:46:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['dromaeo_dom']}, 19:46:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:46:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['basic_compositor_video']}, 19:46:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:46:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'other-e10s': {'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['a11yr', 19:46:27 INFO - 'ts_paint', 19:46:27 INFO - 'tpaint', 19:46:27 INFO - 'sessionrestore', 19:46:27 INFO - 'sessionrestore_no_auto_restore', 19:46:27 INFO - 'tabpaint']}, 19:46:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['tsvgx', 19:46:27 INFO - 'tsvgr_opacity', 19:46:27 INFO - 'tart', 19:46:27 INFO - 'tscrollx', 19:46:27 INFO - 'cart']}, 19:46:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:46:27 INFO - 'tsvgr_opacity', 19:46:27 INFO - 'tart', 19:46:27 INFO - 'tscrollx', 19:46:27 INFO - 'cart']}, 19:46:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'talos_options': ['--disable-e10s'], 19:46:27 INFO - 'tests': ['tp5o']}, 19:46:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'tests': ['tp5o']}, 19:46:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'talos_options': ['--disable-e10s', 19:46:27 INFO - '--xperf_path', 19:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:46:27 INFO - 'tests': ['tp5n']}, 19:46:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:46:27 INFO - 'talos_options': ['--xperf_path', 19:46:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:46:27 INFO - 'tests': ['tp5n']}}, 19:46:27 INFO - 'talos.zip': {'path': '', 19:46:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:46:27 INFO - Running post-action listener: _resource_record_post_action 19:46:27 INFO - [mozharness: 2016-10-18 02:46:27.699967Z] Finished populate-webroot step (success) 19:46:27 INFO - [mozharness: 2016-10-18 02:46:27.700055Z] Running create-virtualenv step. 19:46:27 INFO - Running pre-action listener: _resource_record_pre_action 19:46:27 INFO - Running main action method: create_virtualenv 19:46:27 INFO - Creating virtualenv /builds/slave/test/build/venv 19:46:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:46:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:46:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:46:27 INFO - Using real prefix '/usr' 19:46:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:46:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:46:30 INFO - Installing pip.................done. 19:46:30 INFO - Return code: 0 19:46:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:46:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:46:30 INFO - Reading from file tmpfile_stdout 19:46:30 INFO - Output received: 19:46:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:46:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:46:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:46:30 INFO - Using env: {'DISPLAY': ':0', 19:46:30 INFO - 'HOME': '/home/cltbld', 19:46:30 INFO - 'LANG': 'en_US.UTF-8', 19:46:30 INFO - 'LANGUAGE': 'en_US:en', 19:46:30 INFO - 'LOGNAME': 'cltbld', 19:46:30 INFO - 'MAIL': '/var/mail/cltbld', 19:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:30 INFO - 'MOZ_NO_REMOTE': '1', 19:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:30 INFO - 'NO_EM_RESTART': '1', 19:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:30 INFO - 'PWD': '/builds/slave/test', 19:46:30 INFO - 'SHELL': '/bin/bash', 19:46:30 INFO - 'SHLVL': '1', 19:46:30 INFO - 'TERM': 'linux', 19:46:30 INFO - 'TMOUT': '86400', 19:46:30 INFO - 'USER': 'cltbld', 19:46:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:30 INFO - '_': '/tools/buildbot/bin/python'} 19:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:46:30 INFO - Cleaning up... 19:46:30 INFO - Return code: 0 19:46:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:46:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:46:30 INFO - Using env: {'DISPLAY': ':0', 19:46:30 INFO - 'HOME': '/home/cltbld', 19:46:30 INFO - 'LANG': 'en_US.UTF-8', 19:46:30 INFO - 'LANGUAGE': 'en_US:en', 19:46:30 INFO - 'LOGNAME': 'cltbld', 19:46:30 INFO - 'MAIL': '/var/mail/cltbld', 19:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:30 INFO - 'MOZ_NO_REMOTE': '1', 19:46:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:30 INFO - 'NO_EM_RESTART': '1', 19:46:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:30 INFO - 'PWD': '/builds/slave/test', 19:46:30 INFO - 'SHELL': '/bin/bash', 19:46:30 INFO - 'SHLVL': '1', 19:46:30 INFO - 'TERM': 'linux', 19:46:30 INFO - 'TMOUT': '86400', 19:46:30 INFO - 'USER': 'cltbld', 19:46:30 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:30 INFO - '_': '/tools/buildbot/bin/python'} 19:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:30 INFO - Downloading/unpacking psutil>=3.1.1 19:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:46:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:46:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:46:32 INFO - Installing collected packages: psutil 19:46:32 INFO - Running setup.py install for psutil 19:46:33 INFO - building 'psutil._psutil_linux' extension 19:46:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:46:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:46:33 INFO - building 'psutil._psutil_posix' extension 19:46:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:46:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:46:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:46:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:46:33 INFO - Successfully installed psutil 19:46:33 INFO - Cleaning up... 19:46:33 INFO - Return code: 0 19:46:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:46:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:46:33 INFO - Using env: {'DISPLAY': ':0', 19:46:33 INFO - 'HOME': '/home/cltbld', 19:46:33 INFO - 'LANG': 'en_US.UTF-8', 19:46:33 INFO - 'LANGUAGE': 'en_US:en', 19:46:33 INFO - 'LOGNAME': 'cltbld', 19:46:33 INFO - 'MAIL': '/var/mail/cltbld', 19:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:33 INFO - 'MOZ_NO_REMOTE': '1', 19:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:33 INFO - 'NO_EM_RESTART': '1', 19:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:33 INFO - 'PWD': '/builds/slave/test', 19:46:33 INFO - 'SHELL': '/bin/bash', 19:46:33 INFO - 'SHLVL': '1', 19:46:33 INFO - 'TERM': 'linux', 19:46:33 INFO - 'TMOUT': '86400', 19:46:33 INFO - 'USER': 'cltbld', 19:46:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:33 INFO - '_': '/tools/buildbot/bin/python'} 19:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:46:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:46:35 INFO - Installing collected packages: mozsystemmonitor 19:46:35 INFO - Running setup.py install for mozsystemmonitor 19:46:35 INFO - Successfully installed mozsystemmonitor 19:46:35 INFO - Cleaning up... 19:46:35 INFO - Return code: 0 19:46:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:46:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:46:35 INFO - Using env: {'DISPLAY': ':0', 19:46:35 INFO - 'HOME': '/home/cltbld', 19:46:35 INFO - 'LANG': 'en_US.UTF-8', 19:46:35 INFO - 'LANGUAGE': 'en_US:en', 19:46:35 INFO - 'LOGNAME': 'cltbld', 19:46:35 INFO - 'MAIL': '/var/mail/cltbld', 19:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:35 INFO - 'MOZ_NO_REMOTE': '1', 19:46:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:35 INFO - 'NO_EM_RESTART': '1', 19:46:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:35 INFO - 'PWD': '/builds/slave/test', 19:46:35 INFO - 'SHELL': '/bin/bash', 19:46:35 INFO - 'SHLVL': '1', 19:46:35 INFO - 'TERM': 'linux', 19:46:35 INFO - 'TMOUT': '86400', 19:46:35 INFO - 'USER': 'cltbld', 19:46:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:35 INFO - '_': '/tools/buildbot/bin/python'} 19:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:36 INFO - Downloading/unpacking jsonschema==2.5.1 19:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:46:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:38 INFO - Downloading functools32-3.2.3-2.tar.gz 19:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:46:38 INFO - warning: no files found matching '*.txt' 19:46:38 INFO - no previously-included directories found matching 'build' 19:46:38 INFO - no previously-included directories found matching 'dist' 19:46:38 INFO - no previously-included directories found matching '.git*' 19:46:38 INFO - Installing collected packages: jsonschema, functools32 19:46:38 INFO - Running setup.py install for functools32 19:46:38 INFO - warning: no files found matching '*.txt' 19:46:38 INFO - no previously-included directories found matching 'build' 19:46:38 INFO - no previously-included directories found matching 'dist' 19:46:38 INFO - no previously-included directories found matching '.git*' 19:46:38 INFO - Successfully installed jsonschema functools32 19:46:38 INFO - Cleaning up... 19:46:38 INFO - Return code: 0 19:46:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:46:38 INFO - Using env: {'DISPLAY': ':0', 19:46:38 INFO - 'HOME': '/home/cltbld', 19:46:38 INFO - 'LANG': 'en_US.UTF-8', 19:46:38 INFO - 'LANGUAGE': 'en_US:en', 19:46:38 INFO - 'LOGNAME': 'cltbld', 19:46:38 INFO - 'MAIL': '/var/mail/cltbld', 19:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:38 INFO - 'MOZ_NO_REMOTE': '1', 19:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:38 INFO - 'NO_EM_RESTART': '1', 19:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:38 INFO - 'PWD': '/builds/slave/test', 19:46:38 INFO - 'SHELL': '/bin/bash', 19:46:38 INFO - 'SHLVL': '1', 19:46:38 INFO - 'TERM': 'linux', 19:46:38 INFO - 'TMOUT': '86400', 19:46:38 INFO - 'USER': 'cltbld', 19:46:38 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:38 INFO - '_': '/tools/buildbot/bin/python'} 19:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:46:38 INFO - Cleaning up... 19:46:38 INFO - Return code: 0 19:46:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:46:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:46:38 INFO - Using env: {'DISPLAY': ':0', 19:46:38 INFO - 'HOME': '/home/cltbld', 19:46:38 INFO - 'LANG': 'en_US.UTF-8', 19:46:38 INFO - 'LANGUAGE': 'en_US:en', 19:46:38 INFO - 'LOGNAME': 'cltbld', 19:46:38 INFO - 'MAIL': '/var/mail/cltbld', 19:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:38 INFO - 'MOZ_NO_REMOTE': '1', 19:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:38 INFO - 'NO_EM_RESTART': '1', 19:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:38 INFO - 'PWD': '/builds/slave/test', 19:46:38 INFO - 'SHELL': '/bin/bash', 19:46:38 INFO - 'SHLVL': '1', 19:46:38 INFO - 'TERM': 'linux', 19:46:38 INFO - 'TMOUT': '86400', 19:46:38 INFO - 'USER': 'cltbld', 19:46:38 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:38 INFO - '_': '/tools/buildbot/bin/python'} 19:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:38 INFO - Downloading/unpacking blobuploader==1.2.4 19:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:40 INFO - Downloading blobuploader-1.2.4.tar.gz 19:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:46:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:46:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:41 INFO - Downloading docopt-0.6.1.tar.gz 19:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:46:41 INFO - Installing collected packages: blobuploader, requests, docopt 19:46:41 INFO - Running setup.py install for blobuploader 19:46:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:46:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:46:41 INFO - Running setup.py install for requests 19:46:42 INFO - Running setup.py install for docopt 19:46:42 INFO - Successfully installed blobuploader requests docopt 19:46:42 INFO - Cleaning up... 19:46:42 INFO - Return code: 0 19:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:46:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:46:42 INFO - Using env: {'DISPLAY': ':0', 19:46:42 INFO - 'HOME': '/home/cltbld', 19:46:42 INFO - 'LANG': 'en_US.UTF-8', 19:46:42 INFO - 'LANGUAGE': 'en_US:en', 19:46:42 INFO - 'LOGNAME': 'cltbld', 19:46:42 INFO - 'MAIL': '/var/mail/cltbld', 19:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:42 INFO - 'MOZ_NO_REMOTE': '1', 19:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:42 INFO - 'NO_EM_RESTART': '1', 19:46:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:42 INFO - 'PWD': '/builds/slave/test', 19:46:42 INFO - 'SHELL': '/bin/bash', 19:46:42 INFO - 'SHLVL': '1', 19:46:42 INFO - 'TERM': 'linux', 19:46:42 INFO - 'TMOUT': '86400', 19:46:42 INFO - 'USER': 'cltbld', 19:46:42 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:42 INFO - '_': '/tools/buildbot/bin/python'} 19:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:46:42 INFO - Running setup.py (path:/tmp/pip-efFqou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:46:42 INFO - Running setup.py (path:/tmp/pip-dtEDiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:46:42 INFO - Running setup.py (path:/tmp/pip-fOfaWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:46:42 INFO - Running setup.py (path:/tmp/pip-X9AAPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:46:42 INFO - Running setup.py (path:/tmp/pip-UnGmuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:46:42 INFO - Running setup.py (path:/tmp/pip-dCA6vM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:46:42 INFO - Running setup.py (path:/tmp/pip-PfZ9yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:46:42 INFO - Running setup.py (path:/tmp/pip-H6F5IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:46:42 INFO - Running setup.py (path:/tmp/pip-CTOAo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:46:43 INFO - Running setup.py (path:/tmp/pip-1toV3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:46:43 INFO - Running setup.py (path:/tmp/pip-DbtsBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:46:43 INFO - Running setup.py (path:/tmp/pip-XsRnES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:46:43 INFO - Running setup.py (path:/tmp/pip-1OGcB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:46:43 INFO - Running setup.py (path:/tmp/pip-CZUxZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:43 INFO - Running setup.py (path:/tmp/pip-QKiIwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:46:43 INFO - Running setup.py (path:/tmp/pip-CJJEKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:46:43 INFO - Running setup.py (path:/tmp/pip-uZBrDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:46:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:46:43 INFO - Running setup.py install for manifestparser 19:46:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:46:43 INFO - Running setup.py install for mozcrash 19:46:43 INFO - Running setup.py install for mozdebug 19:46:43 INFO - Running setup.py install for mozdevice 19:46:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozfile 19:46:44 INFO - Running setup.py install for mozhttpd 19:46:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozinfo 19:46:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozInstall 19:46:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozleak 19:46:44 INFO - Running setup.py install for mozlog 19:46:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for moznetwork 19:46:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozprocess 19:46:44 INFO - Running setup.py install for mozprofile 19:46:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:46:44 INFO - Running setup.py install for mozrunner 19:46:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:46:45 INFO - Running setup.py install for mozscreenshot 19:46:45 INFO - Running setup.py install for moztest 19:46:45 INFO - Running setup.py install for mozversion 19:46:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:46:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:46:45 INFO - Cleaning up... 19:46:45 INFO - Return code: 0 19:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:46:45 INFO - Using env: {'DISPLAY': ':0', 19:46:45 INFO - 'HOME': '/home/cltbld', 19:46:45 INFO - 'LANG': 'en_US.UTF-8', 19:46:45 INFO - 'LANGUAGE': 'en_US:en', 19:46:45 INFO - 'LOGNAME': 'cltbld', 19:46:45 INFO - 'MAIL': '/var/mail/cltbld', 19:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:45 INFO - 'MOZ_NO_REMOTE': '1', 19:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:45 INFO - 'NO_EM_RESTART': '1', 19:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:45 INFO - 'PWD': '/builds/slave/test', 19:46:45 INFO - 'SHELL': '/bin/bash', 19:46:45 INFO - 'SHLVL': '1', 19:46:45 INFO - 'TERM': 'linux', 19:46:45 INFO - 'TMOUT': '86400', 19:46:45 INFO - 'USER': 'cltbld', 19:46:45 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:45 INFO - '_': '/tools/buildbot/bin/python'} 19:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:46:45 INFO - Running setup.py (path:/tmp/pip-LvlI5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:46:45 INFO - Running setup.py (path:/tmp/pip-F2oSNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:46:45 INFO - Running setup.py (path:/tmp/pip-QtaYE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:46:45 INFO - Running setup.py (path:/tmp/pip-JrMeCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:46:45 INFO - Running setup.py (path:/tmp/pip-l6rrXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:46:46 INFO - Running setup.py (path:/tmp/pip-pUbbE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:46:46 INFO - Running setup.py (path:/tmp/pip-KgvrDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:46:46 INFO - Running setup.py (path:/tmp/pip-r3YZ5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:46:46 INFO - Running setup.py (path:/tmp/pip-TXeTjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:46:46 INFO - Running setup.py (path:/tmp/pip-oXEVtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:46:46 INFO - Running setup.py (path:/tmp/pip-VOq7dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:46:46 INFO - Running setup.py (path:/tmp/pip-uBiy03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:46:46 INFO - Running setup.py (path:/tmp/pip-i2dVsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:46:46 INFO - Running setup.py (path:/tmp/pip-mYZ6uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:46 INFO - Running setup.py (path:/tmp/pip-jd7fue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:46:46 INFO - Running setup.py (path:/tmp/pip-Ya1R9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:46:46 INFO - Running setup.py (path:/tmp/pip-EgxsYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:46:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:46:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:46:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:46:48 INFO - Downloading blessings-1.6.tar.gz 19:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:46:48 INFO - Installing collected packages: blessings 19:46:48 INFO - Running setup.py install for blessings 19:46:48 INFO - Successfully installed blessings 19:46:48 INFO - Cleaning up... 19:46:49 INFO - Return code: 0 19:46:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:46:49 INFO - Reading from file tmpfile_stdout 19:46:49 INFO - Current package versions: 19:46:49 INFO - argparse == 1.2.1 19:46:49 INFO - blessings == 1.6 19:46:49 INFO - blobuploader == 1.2.4 19:46:49 INFO - docopt == 0.6.1 19:46:49 INFO - functools32 == 3.2.3-2 19:46:49 INFO - jsonschema == 2.5.1 19:46:49 INFO - manifestparser == 1.1 19:46:49 INFO - mozInstall == 1.12 19:46:49 INFO - mozcrash == 0.17 19:46:49 INFO - mozdebug == 0.1 19:46:49 INFO - mozdevice == 0.48 19:46:49 INFO - mozfile == 1.2 19:46:49 INFO - mozhttpd == 0.7 19:46:49 INFO - mozinfo == 0.9 19:46:49 INFO - mozleak == 0.1 19:46:49 INFO - mozlog == 3.4 19:46:49 INFO - moznetwork == 0.27 19:46:49 INFO - mozprocess == 0.23 19:46:49 INFO - mozprofile == 0.28 19:46:49 INFO - mozrunner == 6.12 19:46:49 INFO - mozscreenshot == 0.1 19:46:49 INFO - mozsystemmonitor == 0.3 19:46:49 INFO - moztest == 0.7 19:46:49 INFO - mozversion == 1.4 19:46:49 INFO - psutil == 3.1.1 19:46:49 INFO - requests == 1.2.3 19:46:49 INFO - wsgiref == 0.1.2 19:46:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:46:49 INFO - Using env: {'DISPLAY': ':0', 19:46:49 INFO - 'HOME': '/home/cltbld', 19:46:49 INFO - 'LANG': 'en_US.UTF-8', 19:46:49 INFO - 'LANGUAGE': 'en_US:en', 19:46:49 INFO - 'LOGNAME': 'cltbld', 19:46:49 INFO - 'MAIL': '/var/mail/cltbld', 19:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:49 INFO - 'MOZ_NO_REMOTE': '1', 19:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:49 INFO - 'NO_EM_RESTART': '1', 19:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:49 INFO - 'PWD': '/builds/slave/test', 19:46:49 INFO - 'SHELL': '/bin/bash', 19:46:49 INFO - 'SHLVL': '1', 19:46:49 INFO - 'TERM': 'linux', 19:46:49 INFO - 'TMOUT': '86400', 19:46:49 INFO - 'USER': 'cltbld', 19:46:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:49 INFO - '_': '/tools/buildbot/bin/python'} 19:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:46:49 INFO - Cleaning up... 19:46:49 INFO - Return code: 0 19:46:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe811df1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14988a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1565790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, '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': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:46:49 INFO - Using env: {'DISPLAY': ':0', 19:46:49 INFO - 'HOME': '/home/cltbld', 19:46:49 INFO - 'LANG': 'en_US.UTF-8', 19:46:49 INFO - 'LANGUAGE': 'en_US:en', 19:46:49 INFO - 'LOGNAME': 'cltbld', 19:46:49 INFO - 'MAIL': '/var/mail/cltbld', 19:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:46:49 INFO - 'MOZ_NO_REMOTE': '1', 19:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:46:49 INFO - 'NO_EM_RESTART': '1', 19:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:46:49 INFO - 'PWD': '/builds/slave/test', 19:46:49 INFO - 'SHELL': '/bin/bash', 19:46:49 INFO - 'SHLVL': '1', 19:46:49 INFO - 'TERM': 'linux', 19:46:49 INFO - 'TMOUT': '86400', 19:46:49 INFO - 'USER': 'cltbld', 19:46:49 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:46:49 INFO - '_': '/tools/buildbot/bin/python'} 19:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:46:49 INFO - Cleaning up... 19:46:49 INFO - Return code: 0 19:46:49 INFO - Running post-action listener: _resource_record_post_action 19:46:49 INFO - Running post-action listener: _start_resource_monitoring 19:46:49 INFO - Starting resource monitoring. 19:46:49 INFO - [mozharness: 2016-10-18 02:46:49.762303Z] Finished create-virtualenv step (success) 19:46:49 INFO - [mozharness: 2016-10-18 02:46:49.762825Z] Running install step. 19:46:49 INFO - Running pre-action listener: _resource_record_pre_action 19:46:49 INFO - Running main action method: install 19:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:46:49 INFO - Reading from file tmpfile_stdout 19:46:49 INFO - Detecting whether we're running mozinstall >=1.0... 19:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:46:50 INFO - Reading from file tmpfile_stdout 19:46:50 INFO - Output received: 19:46:50 INFO - Usage: mozinstall [options] installer 19:46:50 INFO - Options: 19:46:50 INFO - -h, --help show this help message and exit 19:46:50 INFO - -d DEST, --destination=DEST 19:46:50 INFO - Directory to install application into. [default: 19:46:50 INFO - "/builds/slave/test"] 19:46:50 INFO - --app=APP Application being installed. [default: firefox] 19:46:50 INFO - mkdir: /builds/slave/test/build/application 19:46:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:47:06 INFO - Reading from file tmpfile_stdout 19:47:06 INFO - Output received: 19:47:06 INFO - /builds/slave/test/build/application/firefox/firefox 19:47:06 INFO - Running post-action listener: _resource_record_post_action 19:47:06 INFO - [mozharness: 2016-10-18 02:47:06.986335Z] Finished install step (success) 19:47:06 INFO - [mozharness: 2016-10-18 02:47:06.986579Z] Running run-tests step. 19:47:06 INFO - Running pre-action listener: _resource_record_pre_action 19:47:06 INFO - Running main action method: run_tests 19:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:47:06 INFO - Python 2.7.3 19:47:06 INFO - Return code: 0 19:47:06 INFO - grabbing minidump binary from tooltool 19:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:47:06 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 0x1562930>, '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 0x1563830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1563cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:47:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:47:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:47:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:47:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:47:07 INFO - Return code: 0 19:47:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:47:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:47:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:47:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:47:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 19:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 19:47:07 INFO - Using env: {'DISPLAY': ':0', 19:47:07 INFO - 'HOME': '/home/cltbld', 19:47:07 INFO - 'LANG': 'en_US.UTF-8', 19:47:07 INFO - 'LANGUAGE': 'en_US:en', 19:47:07 INFO - 'LOGNAME': 'cltbld', 19:47:07 INFO - 'MAIL': '/var/mail/cltbld', 19:47:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:47:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:47:07 INFO - 'MOZ_NO_REMOTE': '1', 19:47:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:47:07 INFO - 'NO_EM_RESTART': '1', 19:47:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:47:07 INFO - 'PWD': '/builds/slave/test', 19:47:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:47:07 INFO - 'SHELL': '/bin/bash', 19:47:07 INFO - 'SHLVL': '1', 19:47:07 INFO - 'TERM': 'linux', 19:47:07 INFO - 'TMOUT': '86400', 19:47:07 INFO - 'USER': 'cltbld', 19:47:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045', 19:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:47:07 INFO - '_': '/tools/buildbot/bin/python'} 19:47:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 19:47:07 INFO - mozversion application_buildid: 20161017163001 19:47:07 INFO - mozversion application_changeset: 56b3f2c6f53e72698fea6c25130efceef2a26548 19:47:07 INFO - mozversion application_display_name: Nightly 19:47:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:47:07 INFO - mozversion application_name: Firefox 19:47:07 INFO - mozversion application_remotingname: firefox 19:47:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:47:07 INFO - mozversion application_vendor: Mozilla 19:47:07 INFO - mozversion application_version: 52.0a1 19:47:07 INFO - mozversion platform_buildid: 20161017163001 19:47:07 INFO - mozversion platform_changeset: 56b3f2c6f53e72698fea6c25130efceef2a26548 19:47:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:47:07 INFO - mozversion platform_version: 52.0a1 19:47:07 INFO - using testdate: 1476758827 19:47:07 INFO - actual date: 1476758827 19:47:07 INFO - starting webserver on 'localhost:34246' 19:47:07 INFO - SUITE-START | Running 2 tests 19:47:07 INFO - TEST-START | dromaeo_css 19:47:07 INFO - operating with platform_type : linux_ 19:47:07 INFO - Initialising browser for dromaeo_css test... 19:47:07 INFO - TEST-INFO | started process 2153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCBUirF/profile http://localhost:34246/getInfo.html) 19:47:11 INFO - PROCESS | 2153 | __metrics Screen width/height:1600/1200 19:47:11 INFO - PROCESS | 2153 | colorDepth:24 19:47:11 INFO - PROCESS | 2153 | Browser inner width/height: 1024/697 19:47:11 INFO - PROCESS | 2153 | __metrics 19:47:12 INFO - PROCESS | 2153 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:47:12 INFO - PROCESS | 2153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:47:12 INFO - PROCESS | 2153 | console.error: PushService: 19:47:12 INFO - PROCESS | 2153 | stateChangeProcessEnqueue: Error transitioning state 19:47:12 INFO - PROCESS | 2153 | UnknownError 19:47:13 INFO - TEST-INFO | 2153: exit 0 19:47:13 INFO - Browser initialized. 19:47:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:47:13 INFO - TEST-INFO | started process 2238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCBUirF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:47:25 INFO - PROCESS | 2238 | RSS: Main: 172314624 19:47:25 INFO - PROCESS | 2238 | 19:48:17 INFO - PROCESS | 2238 | 1476758897091 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:50:27 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34246/tests/dromaeo/cssquery-ext.html) 19:50:29 INFO - PROCESS | 2238 | RSS: Main: 275648512 19:50:29 INFO - PROCESS | 2238 | 19:53:32 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-ext.html (next: http://localhost:34246/tests/dromaeo/cssquery-jquery.html) 19:53:32 INFO - PROCESS | 2238 | RSS: Main: 288636928 19:53:32 INFO - PROCESS | 2238 | 19:56:34 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34246/tests/dromaeo/cssquery-mootools.html) 19:56:35 INFO - PROCESS | 2238 | RSS: Main: 218464256 19:56:35 INFO - PROCESS | 2238 | 19:59:37 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34246/tests/dromaeo/cssquery-prototype.html) 19:59:37 INFO - PROCESS | 2238 | RSS: Main: 247279616 19:59:37 INFO - PROCESS | 2238 | 20:02:40 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34246/tests/dromaeo/cssquery-yui.html) 20:02:41 INFO - PROCESS | 2238 | RSS: Main: 220258304 20:02:41 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | Cycle 1(1): loaded http://localhost:34246/tests/dromaeo/cssquery-yui.html (next: http://localhost:34246/tests/dromaeo/cssquery-dojo.html) 20:05:44 INFO - PROCESS | 2238 | RSS: Main: 313536512 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | __start_tp_report 20:05:44 INFO - PROCESS | 2238 | _x_x_mozilla_page_load 20:05:44 INFO - PROCESS | 2238 | _x_x_mozilla_page_load_details 20:05:44 INFO - PROCESS | 2238 | |i|pagename|runs| 20:05:44 INFO - PROCESS | 2238 | |0;dojo.html;2954.0547024952016;2973.8215707057593;2993.940121197576;3007.639083310003;3030.772692048097;5042.596592272618;5129.461406552305;5194.688318700876;5283.996040752259;5360.3299587551555;5174.379074511055;5197.714125723083;5272.261883336336;5293.814716484914;5351.384590772062;4718.551737584924;4719.457262414832;4770.165221086296;4789.568938795508;4791.329213910038;4803.807847686106;4835.492273311304;4874.439439464471;4876.097921884445;4939.975300123494;4634.490206077328;4805.567498925114;4817.108834865561;4864.708117512942;4896.804127834882;4661.930071048937;4725.929111063337;4733.053389322149;4773.856784296477;4790.233562629997;4742.762861856928;4746.074515469485;4753.073150735608;4808.831690890802;4816.879578010543;5241.73791310433;5244.8688782780355;5246.449122842085;5252.159654455307;5260.947390526084;5405.783768649249;5478.45215478453;5592.384837667853;5601.075822489284;5605;5670.376258611549;5670.914936275959;5706.857328566777;5746.856328591819;5752.194692743019;5294.047071527124;5296.973515132477;5301.946980530222;5303.28405665238;5304.443033481506;5511.090670039479;5580.804671836466;5744.080947048452;5748.913766293509;5821.18503409523;5376.354837419534;5462.071447853795;5469.535089517356;5526.336839579196;5527.751251193706;5466.480684334982;5507.476789704971;5512.862178445571;5599.608027438122;5600.411956744565;5574;5576.414476479992;5577.414371491016;5581.49766521015;5646.971765141148;5332.520073193431;5371.328583927009;5514.228008078872;5516.91724624131;5517.917231241535;4270.08193238449;4295.656347492193;4295.897887149845;4296.914061718748;4349.521552629209;3816.1413681921345;3816.7709937403843;3827.9808600956817;3857.7685338879755;3874.283257597297;5068.442471328225;5125.154349532286;5202.297689811902;5218.530332270038;5233.659812112277;5561.916571251353;5567.638103523258;5574.777008919597;5584.972075139598;5598.97200514003;5244.659097158673;5254.290670759475;5258.106121959276;5262.236975638588;5329.413764485904;4726.409198850144;4777.928331075037;4778.450478194983;4820.421549414023;4872.293517439952;5701.600887937804;5703.600747947604;5704.258446401941;5705.400932902152;5711.086226203871;5533.806316779054;5541.778328866801;5546.168074788814;5546.889062218814;5550.111982082847;5659.971700141473;5701.515371193495;5708.200851880823;5716.342620598518;5718.51392631631;5441.020616289106;5443.374011988672;5447.12845944647;5452.781888724407;5455.236266922555;5482.013237617267;5487.588430867621;5488.945110549003;5495;5511.696856672761;5378.97310513445;5389.137737961907;5393.865153371197;5394.622376433769;5394.649347792381;5434.184872269191;5459.071957767269;5460.918086228789;5464.2076898848645;5465.754041068082;5208.73956302191;5258.421573626822;5408.215808707794;5411.107167317348;5462.289902312674;5454.04554203005;5496.258005169411;5499.560035197094;5536.944630553643;5600.719964001865;5393;5412.215228791724;5414.485623865727;5415.864603384947;5416.647917885325 20:05:44 INFO - PROCESS | 2238 | |1;ext.html;608.4007252855936;637.1716768201337;644.2430144580118;644.6615526848407;650.9609423434595;41896;43158.58407610517;43288;46302.768486157525;46443.76778116113;44043.77978110113;44499.11001779963;44591.77704111475;44640;44650.10699786002;8080.1515840836455;8698.347623928215;8870.86693699595;8871.37900346976;8881.689140880071;8031.477953933005;10402.583896644162;10629.627963021296;10645.574177032948;11014.44927753613;10787.838182427231;10846.18653600979;10849.124981879808;10854.511546980366;10857.51141198644;8078.757637270891;10657.307275027137;10657.733556661067;10669.093127084207;10739.301945373525;7917.722879699183;10110.393376397366;10412.94793526035;10439.112675422599;10771.299865508796;41151.1769764606;41420.792896035324;41509.584904151176;41533.1693366134;41534.37698434526;3430.622631510557;3432.7253819694365;3466.618671946084;3565.286942611488;3959.2477429288633;31509.488584748513;31746.682533174604;32039.839800800848;32286;32325.676743232503;4299.333603291495;4357.150355680644;4390.912181756379;4395.340698895159;4407.581279778416;309069.4546527275;310287;311175;312031;313637;55970.440295597335;56304.436955630736;56362;56394.7180264096;56421.408360578804;59905.400945990856;60098.09852852211;60149.586537211864;60199.39800601938;60562.69718651378;14163.716725665636;14284.928575357057;14300.284985750672;14309.21299328526;14320.06919550226;10513.421761803022;10737.248392612442;10776.78446431067;10825.729356766144;10991.505382257817;15642.374305027899;16311.755323670155;16364.427245046367;16364.427245046367;16412.589685257964;5765;5889.558283128783;5966.433188847052;5994.37059108796;6008.3390827009;900.6442455230148;1014.2443879309814;1024.1601886453186;1039.599754094679;1107.7230692326918;31002.844985775377;31015.534766978515;31109.533356999662;31762;31878.362432751215;1046.842973553973;1127.2710977449683;1130.7512347283584;1142.9657110286705;1158.7219067423757;14931.92534037323;15834.60413489672;15896.523104306889;15911.681766364607;16121.919390403207;425465;426897;428920;429738;635113;49284.26073608899;49805.50194498081;53556.46443535593;54371.184432233546;54559.18161227585;1281.8461784585909;1313.6190504753706;1337.237774468563;1365.5903229031449;1374.3883971632526;29688.109356719335;31200;31205.219869502536;31218.843905780326;31852;49646.25530617116;50120;50144.74927625339;50227;50236.246456302455;393821;395762;396025;396048;397255;26663.067332543542;26734.465310694068;26967.190984270503;27170.72829271682;27248.863755681887;45265.54734452613;45975;46140.7692961533;46254;46844.765776172266;37864.242715146094;39260.80369598134;39449.40825887556;40353.79823100866;41143;38625.2274954505;39244.60755392409;39616.80191599139;39693.60306397015;40550.59449405468;39006.6099339003;39008.414873776346;39015;39779.801100995464;40409.797951010056;31659;34787.30425391428;35002;35020.82489587536;35224;26052.478950421264;26300.473990520466;26431.867840660674;26598.65712801256;26704.465910682065 20:05:44 INFO - PROCESS | 2238 | |2;jquery.html;367.4488267598602;375.25324604037957;384.1778593809249;387.1656580069949;418.91621675664857;14104.788428173573;17481.562960925967;18051.729224061648;18767.343142989997;19864.70202946957;18253.0873456327;18566;18914.14886330115;19184.71222931657;19199.90400047998;5447.346318441792;5725.885482290372;5727.684977326246;5729.427057294258;5731.340895796995;4113.136241389297;4303.8063287152;4347.934780978288;5624.21261023457;6362.713677884483;6341.953577659704;6389.329120442357;6398.584092034026;6404.487640988733;6415.67921603918;6074.605150665215;6258.968705156468;6284.968575157141;6288.308286088527;6293.3391993840905;5034.270030845543;5282.630215884889;5522.834314970517;5524.806631767909;5525.723713814293;11632.243904146204;11784.234024788448;11802.11484138693;11808;12205.877941220562;2140.743110826695;2143.9035243414082;2145.3349461666776;2192.539566690981;2242.6411774116227;8897.199252067345;9699.563519641633;10066.69799906004;10238.846417303746;10364.222683298784;1931.9323823666105;1934.2359767891744;1945.387203031055;1946.2020571565731;1977.2881762565464;77692.99999999943;78431;79029.60485197537;80497.59751201265;84737.15262847354;10086.293959422917;10343;10419.270651054505;10464.633737819293;10474.423906685053;14480.710385792225;14655.780163297559;14664.560063198121;15175.696486070217;15203.619909502351;7658.234176582298;7775.345587573831;7910.5649189295145;7928.1675424080795;7987.56068416231;6951.756688515878;7048.577085374894;7205.2434494378385;7224.747133850396;7573.924260757432;4394.406755088086;4539.601884512002;4572.977135114304;4636.890214952188;4650.95349046512;1892.858035647332;1896.7723873135312;1902.6955687089999;1950.0834607734341;1967.0722691762153;790.3874497264551;831.6216121664628;907.2832462354797;913.1142791492242;997.0577803975174;17639.823601764074;17995.190216440293;18453.446396608124;18481.630367392576;19101.713474297896;918.4213945214472;934.289939645874;955.9665411710557;956.0821611253136;970.9611615535299;9650.131488165958;10282.280240383101;10295;10338.435306713247;10395.376277423227;432919;447898;448608;449040;453870;89580.5520972404;89680;90413.54793225991;90578.54710726404;92369.53815231015;923.7044145873256;925.3013974449248;946.7207173883721;953.2802733935835;955.4840386191379;15716.371345146066;15896.920515397349;16567.66864662676;16593.751093733845;16733;38699.806500967316;40055.39916901307;40317.39523907202;40401.595984039785;40719.79640101899;53854.73072634612;55891.44108559025;56967;57123.714381427824;58298.41701583099;8035.718749843727;8380.53907035119;8394.160583941557;8499.065102838558;8619.310455163697;10762.784744304914;12381.75236495283;13332.133411328232;13545.729085418434;13659.248741319323;11780.882191177978;11960.820587691365;12000.219985700902;12382.81425778596;12412.317322547347;11356.274494121517;11903.16677832578;11933.701657458632;11968.341741204318;12528.37358132108;11654.30074195551;11932.70168245766;12254.448549815457;12319.938400307941;12380.62858114258;8407.453515521716;8538.10349913275;8654.177853103945;8674.306055515417;8770.166834150745;10187;11534.480948357177;11643.41782910834;11673.474693638636;12003.1597788154 20:05:44 INFO - PROCESS | 2238 | |3;mootools.html;1447.2618964328192;1509.8037255156828;1616.854483096521;1757.534253422844;1769.4072485717293;7943.880841787378;8182.713605023825;8354.039285482171;9006.954965225174;9011.459312441259;8406.453580517278;8728.345374096953;8732.95633521833;8861.069587693297;8871.822563548725;2207.5253820428607;2234.1845226492296;2240.406292332533;2243.562505311465;2252.718410198725;1927.2291083566545;1992.1334219614441;2183.9890800546054;2213.612617791888;2237.5860465813794;2122.182959560565;2184.541246338271;2186.8031877131057;2192.6820611011394;2199.7910198531113;2195.549912267997;2211.524522227721;2217.5897458970144;2218.7559368469456;2220.0453804863823;2162.924297649575;2175.4126385875725;2182.1489619048584;2183.858049226795;2193.3419974007893;8834.293256539462;8950.373473856833;8955.910440895574;8957.776055598597;8960.327975401871;5034.773435195425;5201.9479805201845;5208.5572726318305;5214;5218.8695282617855;3206.8556914938886;3381.070205693429;3427.622961474236;3456.2741824216946;3469.930601387983;948.3551185194066;993.5479356892669;996.5515518016849;1011.585250047477;1012.691129205592;12273.693157671041;12373.505059797688;12382.566610168691;12387.814182787266;12398.938005309916;4291.935620965688;4360.8909777255185;4507.5267096955;4544.272916333437;4551.658625603093;10258.28192026551;10262.692119236435;10271.537780799881;10282.845757313646;10303.227257955683;5208.192730126836;5209.47905209476;5232.764525596357;5234.450382709761;5235.685858848481;4590.104929538775;4623.606993405531;4811.374521312207;4812.037592481518;4816.229403295456;3708.332500149999;3711.3319602471274;3725.18046029873;3796.7152463565344;3812.370958791824;1099.5162128663362;1122.7305446692735;1164.3596022187762;1169.6607983684637;1177.1642134084896;847.7753395350237;879.6657270237268;931.2038207332769;931.4969916245152;954.8424509955919;5836.503897168704;5878.500327472213;5899.410058994066;5975.790847320323;6224.315325314212;848.1179573243758;852.9530875801767;864.2740098317444;912.1243606138181;938.2353381993653;3800.201957588882;3860.7104467165077;3888.766673999569;4043.6765058795227;4051.0480037191824;10184.490775461198;10390;10489.475526223658;10520.26358154937;10723.94638026805;10504.894951050392;10660.360378377321;10669.306495077795;10670.199735019905;10679.359238445719;8187.385946053951;8352.373571982003;8404.243618074403;8407.53758543286;8419.579021049045;5933.792317268875;5961.314448838439;5997.610155339889;6068.4841788448475;6151.231096112986;9723.222142228746;10317.071463568314;10329.586816527555;10396.428196448966;10473.581056757645;10521.894781052091;10569.365838049742;10580.576776928836;10598.15214782831;10602.310849794738;7966.522008679498;8056.637451314587;8061.717839875576;8075.434719569574;8087.514749115072;8104.311133553478;8161.347092232726;8266.958665206635;8267.793305167419;8291.792705182177;7661.118971318369;7674.6930122794465;7702.922970770221;7805.765827025199;7911.683532658629;7450.329470347695;7555.6977720890545;7716.263840636037;7766.88349674744;7820.452568320163;6989.30106989297;7008.894866576903;7027.7188912442925;7040.239940014996;7046.894296585453;5000.974995125001;5027.773750181177;5073.543381095719;5118.181091025418;5142.820001299937;5128.487151284841;5187.792488300426;5291.074062039204;5331.493508116722;5378.569714422915 20:05:44 INFO - PROCESS | 2238 | |4;prototype.html;318.925052612636;319.2609109910557;325.28275153286995;331.491161067761;332.56600136821436;5107.259447380127;5240.580753539717;5287.41838397776;5355.143177091666;5355.830888490163;5073.162928116856;5184.611154163434;5205.141151709963;5215.217717342401;5227.555657769085;4084.2852500812387;4107.17856428714;4109.342505199169;4116.238495878265;4120.381942708587;3920.8823735287842;4023.3763766616075;4118.567550407209;4119.526254480743;4150.43969064177;4035.1122752994293;4038.3538633818594;4039.4142849286836;4051.7642119153793;4056.574059723731;4006.419069234974;4027.2750904837117;4034.7780872052026;4040.8585699500377;4042.3734321180264;3572.1426857554;3572.8749493767596;3573.535440392758;3575.785452872836;3596.946045809315;5023.673461225008;5077.517635824591;5080.1617733074;5087.804365973993;5136.537711605936;4616;4629.916537558079;4630.629549636021;4650.813967441299;4681.812727490897;5493.533049690741;5509;5528.364238112628;5535.916961245584;5542.251796007527;3795.7532760370486;3897.7466464679705;3903.394973779069;3908.6743387684282;3913.256481268578;5579.944200557983;5621.80323688669;5623.862932085822;5639.661620302795;5656.971715141439;3974.463447434617;3980.1641655252142;3982.1682026267663;3984.541777695544;4022.718409711292;3839.1745774658584;4027.509821366113;4033.1127152026506;4070.776107314126;4074.7351422157467;4607.631389488833;4610.723356598614;4612.846788302924;4620.214563524209;4627.398438203012;5511.118221084607;5511.22842802008;5513.558915286768;5513.779448822002;5522.502974732293;1325.5426877727361;1342.161149281699;1363.8704323089291;1392.2551434982438;1392.8746412822895;934.1545900959643;940.8776859008285;941.6280569175275;958.6453012385462;973.2116985241977;5247.9737601311745;5248.527632513092;5254.710990895537;5257.474252574789;5333.306670132935;5559.916601250984;5571.8240602855985;5584.664920104807;5619.044762390404;5644.57665675076;5083.339165908482;5230.241614965808;5233.215017747292;5249.868753281198;5332.413434522199;2963.074001898783;2985.268609190762;2989.7458716008946;2998.6551546571986;3004.444177827109;5834.620749651259;5863;5865.325487568899;5901.321348044944;5902.439268269508;5559.193916882029;5729.914051289234;5740.425957404226;5750.591105179258;5793.479836271151;5779.971100144472;5795.420457954171;5824;5835.978703726916;5877.4122587742595;5382.7308634567335;5579.9163012555655;5602.5517958562405;5729.427057294237;5735;5553.111502159797;5564.19319198722;5595.384507704231;5595.608307418442;5597.412271711411;5808.39011903744;5831.883362332815;5839.883202336015;5863.296404431496;5904.3210030845285;3859.5333773166017;3946.9210615788097;3960.8315546914305;3964.544077431017;3980.820863061111;5291.9470805292995;5567.331920169444;5581.190727344431;5603.439656034364;5618.831435056954;5350.261165121274;5384.111621582395;5519.613627046068;5541.473560011793;5582.804601838916;5460.972695136499;5528.834134975958;5530.944690553043;5550.972245138749;5576.86057848557;5272.420033796356;5422.755975981011;5424.2134890439875;5485.149801780731;5489.286392768914;5507.686771556037;5538.255449533384;5543.861403464945;5550.47270509301;5553.527950124285;5403.838174792439;5465.262189604353;5493.505584497414;5500.669959802424;5505.752241149238 20:05:44 INFO - PROCESS | 2238 | |5;yui.html;678.942289905358;696.2202333386608;731.7987553422809;745.1468069060926;825.7976795685056;15693.686126277482;16269.755953660704;16337.509874703763;16353.672926541461;16359.182040897967;14481;14574.906881983841;14616.707665846676;14623.561293161221;14623.92688036561;641.5477088652489;665.9134312539363;773.0800347586382;806.0689903161842;810.2181394953868;798.0423491809822;799.9120096789349;800.3317230112863;800.3437181511163;803.2931020701803;871.0636066228798;873.8164985353084;874.6501399440211;874.7550685807984;874.8731433942074;678.145536623855;710.1620088295809;792.3067316098413;844.1769274956929;867.0679020053468;625.2184769038702;628.2680677011276;628.6416742456802;629.2291942370605;629.3769168523174;14722.631934201623;14800.259987000607;14805.925970370079;14823.481178158712;14828.258587070712;13813.723725525433;14095.295235238196;14354.282285885663;14468.421263149461;14473.276336183255;12708.87291127086;12777.361131943459;12966.80549791754;13201;13501.729965400735;4252.425922500453;4314.346567636957;4383.583559561868;4384.605385515287;4421.9336709949375;79694.60152699257;80781.59609201975;81012.59493702553;81541;81594;11005.77758535753;11193.216474846846;11208.103351732005;11431.714207144722;11897;21374;21485.46286342854;21731.130754769947;21739.45651358697;21746.78253217479;8096.149904259968;8219.383546234056;8241.79395515117;8289.668413263402;8290.37822163328;4677.158111539956;4695.906081878412;4703.764811759398;4798.832040878552;4800.591949684246;3319.3195394943978;3398.337324221753;3399.66003399663;3413.197898493852;3420.0936751760805;572.1475002246706;580.8083332500349;593.679413116912;598.7305712429337;613.9631622102688;584.3659629302169;647.2653538234138;654.3750718064238;677.932206779328;695.1310861423221;8603.440776349642;8967.327450441244;8980.8652870207;8982.685606003759;9017.368784185175;537.3256563013393;587.538782056084;633.8859005378984;650.8015055408146;661.0315887225238;4032.206119162641;4190;4236.173946080485;4330.675199359997;4410.514843367227;116256;120477.39761301137;120523.39738301428;121047;122871;83789;100941.44822034858;102172;104094.47952760187;104431;54073.72963135159;57266.7136664314;78760.60619696864;80506.5974670123;83782;8962.283017358464;9372.67195648149;9547.904520954702;9649.372790768577;9693.22454203648;36427.45358819673;36532.63467365292;46683.53316466792;46703.766481167375;47467.28799068086;44439;48962.51037489579;49363.50636493589;63441;73573;12951.288256795131;13045.152065115737;13142.80285795733;13242.933785331012;13258.204507729568;13852.653683657989;13860.237686927316;14182.361793719101;14395.136291822524;14445.638859028188;13724.725505489634;13905.930470347583;14896.702065958836;14979.700405991602;14981;13526.797098043735;14112.29438528049;14366.856331436551;14396.928015359856;14880.255987200813;13154.092656564935;13579.52471663487;13632.522861699794;13884.51404200848;13907.443702252202;10998.835017474905;11151.888481115086;11312.886871131182;11326.37704926204;11508.925999145475;8948.328875334506;9306.813863722824;9513.286503512127;10429.804552976442;10910.084179682714 20:05:44 INFO - PROCESS | 2238 | __end_tp_report 20:05:44 INFO - PROCESS | 2238 | __start_cc_report 20:05:44 INFO - PROCESS | 2238 | _x_x_mozilla_cycle_collect,2200 20:05:44 INFO - PROCESS | 2238 | __end_cc_report 20:05:44 INFO - PROCESS | 2238 | __startTimestamp1476759944389__endTimestamp 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | ------- Summary: start ------- 20:05:44 INFO - PROCESS | 2238 | Number of tests: 6 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#0] dojo.html Cycles:165 Average:5178.37 Median:5393.00 stddev:575.05 (10.7%) stddev-sans-first:549.70 20:05:44 INFO - PROCESS | 2238 | Values: 2954.1 2973.8 2993.9 3007.6 3030.8 5042.6 5129.5 5194.7 5284.0 5360.3 5174.4 5197.7 5272.3 5293.8 5351.4 4718.6 4719.5 4770.2 4789.6 4791.3 4803.8 4835.5 4874.4 4876.1 4940.0 4634.5 4805.6 4817.1 4864.7 4896.8 4661.9 4725.9 4733.1 4773.9 4790.2 4742.8 4746.1 4753.1 4808.8 4816.9 5241.7 5244.9 5246.4 5252.2 5260.9 5405.8 5478.5 5592.4 5601.1 5605.0 5670.4 5670.9 5706.9 5746.9 5752.2 5294.0 5297.0 5301.9 5303.3 5304.4 5511.1 5580.8 5744.1 5748.9 5821.2 5376.4 5462.1 5469.5 5526.3 5527.8 5466.5 5507.5 5512.9 5599.6 5600.4 5574.0 5576.4 5577.4 5581.5 5647.0 5332.5 5371.3 5514.2 5516.9 5517.9 4270.1 4295.7 4295.9 4296.9 4349.5 3816.1 3816.8 3828.0 3857.8 3874.3 5068.4 5125.2 5202.3 5218.5 5233.7 5561.9 5567.6 5574.8 5585.0 5599.0 5244.7 5254.3 5258.1 5262.2 5329.4 4726.4 4777.9 4778.5 4820.4 4872.3 5701.6 5703.6 5704.3 5705.4 5711.1 5533.8 5541.8 5546.2 5546.9 5550.1 5660.0 5701.5 5708.2 5716.3 5718.5 5441.0 5443.4 5447.1 5452.8 5455.2 5482.0 5487.6 5488.9 5495.0 5511.7 5379.0 5389.1 5393.9 5394.6 5394.6 5434.2 5459.1 5460.9 5464.2 5465.8 5208.7 5258.4 5408.2 5411.1 5462.3 5454.0 5496.3 5499.6 5536.9 5600.7 5393.0 5412.2 5414.5 5415.9 5416.6 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#1] ext.html Cycles:180 Average:55465.57 Median:38244.74 stddev:105876.29 (276.8%) stddev-sans-first:106093.19 20:05:44 INFO - PROCESS | 2238 | Values: 608.4 637.2 644.2 644.7 651.0 41896.0 43158.6 43288.0 46302.8 46443.8 44043.8 44499.1 44591.8 44640.0 44650.1 8080.2 8698.3 8870.9 8871.4 8881.7 8031.5 10402.6 10629.6 10645.6 11014.4 10787.8 10846.2 10849.1 10854.5 10857.5 8078.8 10657.3 10657.7 10669.1 10739.3 7917.7 10110.4 10412.9 10439.1 10771.3 41151.2 41420.8 41509.6 41533.2 41534.4 3430.6 3432.7 3466.6 3565.3 3959.2 31509.5 31746.7 32039.8 32286.0 32325.7 4299.3 4357.2 4390.9 4395.3 4407.6 309069.5 310287.0 311175.0 312031.0 313637.0 55970.4 56304.4 56362.0 56394.7 56421.4 59905.4 60098.1 60149.6 60199.4 60562.7 14163.7 14284.9 14300.3 14309.2 14320.1 10513.4 10737.2 10776.8 10825.7 10991.5 15642.4 16311.8 16364.4 16364.4 16412.6 5765.0 5889.6 5966.4 5994.4 6008.3 900.6 1014.2 1024.2 1039.6 1107.7 31002.8 31015.5 31109.5 31762.0 31878.4 1046.8 1127.3 1130.8 1143.0 1158.7 14931.9 15834.6 15896.5 15911.7 16121.9 425465.0 426897.0 428920.0 429738.0 635113.0 49284.3 49805.5 53556.5 54371.2 54559.2 1281.8 1313.6 1337.2 1365.6 1374.4 29688.1 31200.0 31205.2 31218.8 31852.0 49646.3 50120.0 50144.7 50227.0 50236.2 393821.0 395762.0 396025.0 396048.0 397255.0 26663.1 26734.5 26967.2 27170.7 27248.9 45265.5 45975.0 46140.8 46254.0 46844.8 37864.2 39260.8 39449.4 40353.8 41143.0 38625.2 39244.6 39616.8 39693.6 40550.6 39006.6 39008.4 39015.0 39779.8 40409.8 31659.0 34787.3 35002.0 35020.8 35224.0 26052.5 26300.5 26431.9 26598.7 26704.5 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#2] jquery.html Cycles:180 Average:27124.28 Median:22416.47 stddev:73885.11 (329.6%) stddev-sans-first:74065.06 20:05:44 INFO - PROCESS | 2238 | Values: 367.4 375.3 384.2 387.2 418.9 14104.8 17481.6 18051.7 18767.3 19864.7 18253.1 18566.0 18914.1 19184.7 19199.9 5447.3 5725.9 5727.7 5729.4 5731.3 4113.1 4303.8 4347.9 5624.2 6362.7 6342.0 6389.3 6398.6 6404.5 6415.7 6074.6 6259.0 6285.0 6288.3 6293.3 5034.3 5282.6 5522.8 5524.8 5525.7 11632.2 11784.2 11802.1 11808.0 12205.9 2140.7 2143.9 2145.3 2192.5 2242.6 8897.2 9699.6 10066.7 10238.8 10364.2 1931.9 1934.2 1945.4 1946.2 1977.3 77693.0 78431.0 79029.6 80497.6 84737.2 10086.3 10343.0 10419.3 10464.6 10474.4 14480.7 14655.8 14664.6 15175.7 15203.6 7658.2 7775.3 7910.6 7928.2 7987.6 6951.8 7048.6 7205.2 7224.7 7573.9 4394.4 4539.6 4573.0 4636.9 4651.0 1892.9 1896.8 1902.7 1950.1 1967.1 790.4 831.6 907.3 913.1 997.1 17639.8 17995.2 18453.4 18481.6 19101.7 918.4 934.3 956.0 956.1 971.0 9650.1 10282.3 10295.0 10338.4 10395.4 432919.0 447898.0 448608.0 449040.0 453870.0 89580.6 89680.0 90413.5 90578.5 92369.5 923.7 925.3 946.7 953.3 955.5 15716.4 15896.9 16567.7 16593.8 16733.0 38699.8 40055.4 40317.4 40401.6 40719.8 53854.7 55891.4 56967.0 57123.7 58298.4 8035.7 8380.5 8394.2 8499.1 8619.3 10762.8 12381.8 13332.1 13545.7 13659.2 11780.9 11960.8 12000.2 12382.8 12412.3 11356.3 11903.2 11933.7 11968.3 12528.4 11654.3 11932.7 12254.4 12319.9 12380.6 8407.5 8538.1 8654.2 8674.3 8770.2 10187.0 11534.5 11643.4 11673.5 12003.2 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#3] mootools.html Cycles:180 Average:5755.79 Median:4908.60 stddev:3329.69 (67.8%) stddev-sans-first:3323.29 20:05:44 INFO - PROCESS | 2238 | Values: 1447.3 1509.8 1616.9 1757.5 1769.4 7943.9 8182.7 8354.0 9007.0 9011.5 8406.5 8728.3 8733.0 8861.1 8871.8 2207.5 2234.2 2240.4 2243.6 2252.7 1927.2 1992.1 2184.0 2213.6 2237.6 2122.2 2184.5 2186.8 2192.7 2199.8 2195.5 2211.5 2217.6 2218.8 2220.0 2162.9 2175.4 2182.1 2183.9 2193.3 8834.3 8950.4 8955.9 8957.8 8960.3 5034.8 5201.9 5208.6 5214.0 5218.9 3206.9 3381.1 3427.6 3456.3 3469.9 948.4 993.5 996.6 1011.6 1012.7 12273.7 12373.5 12382.6 12387.8 12398.9 4291.9 4360.9 4507.5 4544.3 4551.7 10258.3 10262.7 10271.5 10282.8 10303.2 5208.2 5209.5 5232.8 5234.5 5235.7 4590.1 4623.6 4811.4 4812.0 4816.2 3708.3 3711.3 3725.2 3796.7 3812.4 1099.5 1122.7 1164.4 1169.7 1177.2 847.8 879.7 931.2 931.5 954.8 5836.5 5878.5 5899.4 5975.8 6224.3 848.1 853.0 864.3 912.1 938.2 3800.2 3860.7 3888.8 4043.7 4051.0 10184.5 10390.0 10489.5 10520.3 10723.9 10504.9 10660.4 10669.3 10670.2 10679.4 8187.4 8352.4 8404.2 8407.5 8419.6 5933.8 5961.3 5997.6 6068.5 6151.2 9723.2 10317.1 10329.6 10396.4 10473.6 10521.9 10569.4 10580.6 10598.2 10602.3 7966.5 8056.6 8061.7 8075.4 8087.5 8104.3 8161.3 8267.0 8267.8 8291.8 7661.1 7674.7 7702.9 7805.8 7911.7 7450.3 7555.7 7716.3 7766.9 7820.5 6989.3 7008.9 7027.7 7040.2 7046.9 5001.0 5027.8 5073.5 5118.2 5142.8 5128.5 5187.8 5291.1 5331.5 5378.6 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#4] prototype.html Cycles:180 Average:4617.62 Median:5279.92 stddev:1367.30 (25.9%) stddev-sans-first:1332.53 20:05:44 INFO - PROCESS | 2238 | Values: 318.9 319.3 325.3 331.5 332.6 5107.3 5240.6 5287.4 5355.1 5355.8 5073.2 5184.6 5205.1 5215.2 5227.6 4084.3 4107.2 4109.3 4116.2 4120.4 3920.9 4023.4 4118.6 4119.5 4150.4 4035.1 4038.4 4039.4 4051.8 4056.6 4006.4 4027.3 4034.8 4040.9 4042.4 3572.1 3572.9 3573.5 3575.8 3596.9 5023.7 5077.5 5080.2 5087.8 5136.5 4616.0 4629.9 4630.6 4650.8 4681.8 5493.5 5509.0 5528.4 5535.9 5542.3 3795.8 3897.7 3903.4 3908.7 3913.3 5579.9 5621.8 5623.9 5639.7 5657.0 3974.5 3980.2 3982.2 3984.5 4022.7 3839.2 4027.5 4033.1 4070.8 4074.7 4607.6 4610.7 4612.8 4620.2 4627.4 5511.1 5511.2 5513.6 5513.8 5522.5 1325.5 1342.2 1363.9 1392.3 1392.9 934.2 940.9 941.6 958.6 973.2 5248.0 5248.5 5254.7 5257.5 5333.3 5559.9 5571.8 5584.7 5619.0 5644.6 5083.3 5230.2 5233.2 5249.9 5332.4 2963.1 2985.3 2989.7 2998.7 3004.4 5834.6 5863.0 5865.3 5901.3 5902.4 5559.2 5729.9 5740.4 5750.6 5793.5 5780.0 5795.4 5824.0 5836.0 5877.4 5382.7 5579.9 5602.6 5729.4 5735.0 5553.1 5564.2 5595.4 5595.6 5597.4 5808.4 5831.9 5839.9 5863.3 5904.3 3859.5 3946.9 3960.8 3964.5 3980.8 5291.9 5567.3 5581.2 5603.4 5618.8 5350.3 5384.1 5519.6 5541.5 5582.8 5461.0 5528.8 5530.9 5551.0 5576.9 5272.4 5422.8 5424.2 5485.1 5489.3 5507.7 5538.3 5543.9 5550.5 5553.5 5403.8 5465.3 5493.5 5500.7 5505.8 20:05:44 INFO - PROCESS | 2238 | 20:05:44 INFO - PROCESS | 2238 | [#5] yui.html Cycles:180 Average:19892.09 Median:4397.56 stddev:28996.53 (659.4%) stddev-sans-first:29041.98 20:05:44 INFO - PROCESS | 2238 | Values: 678.9 696.2 731.8 745.1 825.8 15693.7 16269.8 16337.5 16353.7 16359.2 14481.0 14574.9 14616.7 14623.6 14623.9 641.5 665.9 773.1 806.1 810.2 798.0 799.9 800.3 800.3 803.3 871.1 873.8 874.7 874.8 874.9 678.1 710.2 792.3 844.2 867.1 625.2 628.3 628.6 629.2 629.4 14722.6 14800.3 14805.9 14823.5 14828.3 13813.7 14095.3 14354.3 14468.4 14473.3 12708.9 12777.4 12966.8 13201.0 13501.7 4252.4 4314.3 4383.6 4384.6 4421.9 79694.6 80781.6 81012.6 81541.0 81594.0 11005.8 11193.2 11208.1 11431.7 11897.0 21374.0 21485.5 21731.1 21739.5 21746.8 8096.1 8219.4 8241.8 8289.7 8290.4 4677.2 4695.9 4703.8 4798.8 4800.6 3319.3 3398.3 3399.7 3413.2 3420.1 572.1 580.8 593.7 598.7 614.0 584.4 647.3 654.4 677.9 695.1 8603.4 8967.3 8980.9 8982.7 9017.4 537.3 587.5 633.9 650.8 661.0 4032.2 4190.0 4236.2 4330.7 4410.5 116256.0 120477.4 120523.4 121047.0 122871.0 83789.0 100941.4 102172.0 104094.5 104431.0 54073.7 57266.7 78760.6 80506.6 83782.0 8962.3 9372.7 9547.9 9649.4 9693.2 36427.5 36532.6 46683.5 46703.8 47467.3 44439.0 48962.5 49363.5 63441.0 73573.0 12951.3 13045.2 13142.8 13242.9 13258.2 13852.7 13860.2 14182.4 14395.1 14445.6 13724.7 13905.9 14896.7 14979.7 14981.0 13526.8 14112.3 14366.9 14396.9 14880.3 13154.1 13579.5 13632.5 13884.5 13907.4 10998.8 11151.9 11312.9 11326.4 11508.9 8948.3 9306.8 9513.3 10429.8 10910.1 20:05:44 INFO - PROCESS | 2238 | -------- Summary: end -------- 20:05:44 INFO - PROCESS | 2238 | 20:05:45 INFO - TEST-INFO | 2238: exit 0 20:05:45 INFO - TEST-OK | dromaeo_css | took 1118107ms 20:05:45 INFO - TEST-START | kraken 20:05:45 INFO - operating with platform_type : linux_ 20:05:45 INFO - Initialising browser for kraken test... 20:05:45 INFO - TEST-INFO | started process 2632 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1oJYdA/profile http://localhost:34246/getInfo.html) 20:05:49 INFO - PROCESS | 2632 | __metrics Screen width/height:1600/1200 20:05:49 INFO - PROCESS | 2632 | colorDepth:24 20:05:49 INFO - PROCESS | 2632 | Browser inner width/height: 1024/697 20:05:49 INFO - PROCESS | 2632 | __metrics 20:05:50 INFO - PROCESS | 2632 | 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" 20:05:50 INFO - PROCESS | 2632 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:05:51 INFO - PROCESS | 2632 | console.error: PushService: 20:05:51 INFO - PROCESS | 2632 | stateChangeProcessEnqueue: Error transitioning state 20:05:51 INFO - PROCESS | 2632 | UnknownError 20:05:51 INFO - TEST-INFO | 2632: exit 0 20:05:51 INFO - Browser initialized. 20:05:51 INFO - Running cycle 1/1 for kraken test... 20:05:51 INFO - TEST-INFO | started process 2742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1oJYdA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:06:03 INFO - PROCESS | 2742 | RSS: Main: 174301184 20:06:03 INFO - PROCESS | 2742 | 20:06:06 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-beat-detection) 20:06:07 INFO - PROCESS | 2742 | RSS: Main: 237150208 20:06:07 INFO - PROCESS | 2742 | 20:06:09 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-dft) 20:06:10 INFO - PROCESS | 2742 | RSS: Main: 215621632 20:06:10 INFO - PROCESS | 2742 | 20:06:13 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-fft) 20:06:14 INFO - PROCESS | 2742 | RSS: Main: 297553920 20:06:14 INFO - PROCESS | 2742 | 20:06:16 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34246/tests/kraken/driver.html?testName=audio-oscillator) 20:06:16 INFO - PROCESS | 2742 | RSS: Main: 206290944 20:06:16 INFO - PROCESS | 2742 | 20:06:19 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:06:19 INFO - PROCESS | 2742 | RSS: Main: 211660800 20:06:19 INFO - PROCESS | 2742 | 20:06:22 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-darkroom) 20:06:23 INFO - PROCESS | 2742 | RSS: Main: 248090624 20:06:23 INFO - PROCESS | 2742 | 20:06:26 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34246/tests/kraken/driver.html?testName=imaging-desaturate) 20:06:27 INFO - PROCESS | 2742 | RSS: Main: 247734272 20:06:27 INFO - PROCESS | 2742 | 20:06:29 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34246/tests/kraken/driver.html?testName=json-parse-financial) 20:06:30 INFO - PROCESS | 2742 | RSS: Main: 246255616 20:06:30 INFO - PROCESS | 2742 | 20:06:31 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:06:32 INFO - PROCESS | 2742 | RSS: Main: 209027072 20:06:32 INFO - PROCESS | 2742 | 20:06:34 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:06:34 INFO - PROCESS | 2742 | RSS: Main: 216363008 20:06:34 INFO - PROCESS | 2742 | 20:06:36 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:06:37 INFO - PROCESS | 2742 | RSS: Main: 225370112 20:06:37 INFO - PROCESS | 2742 | 20:06:40 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:06:40 INFO - PROCESS | 2742 | RSS: Main: 233537536 20:06:40 INFO - PROCESS | 2742 | 20:06:43 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:06:44 INFO - PROCESS | 2742 | RSS: Main: 231755776 20:06:44 INFO - PROCESS | 2742 | 20:06:45 INFO - PROCESS | 2742 | Cycle 1(1): loaded http://localhost:34246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34246/tests/kraken/driver.html?testName=ai-astar) 20:06:46 INFO - PROCESS | 2742 | RSS: Main: 281186304 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | __start_tp_report 20:06:46 INFO - PROCESS | 2742 | _x_x_mozilla_page_load 20:06:46 INFO - PROCESS | 2742 | _x_x_mozilla_page_load_details 20:06:46 INFO - PROCESS | 2742 | |i|pagename|runs| 20:06:46 INFO - PROCESS | 2742 | |0;ai-astar;105;113;114;112;111;114;112;113;114;112 20:06:46 INFO - PROCESS | 2742 | |1;audio-beat-detection;149;122;123;156;125;125;119;122;153;127 20:06:46 INFO - PROCESS | 2742 | |2;audio-dft;160;161;163;162;163;160;161;161;161;161 20:06:46 INFO - PROCESS | 2742 | |3;audio-fft;76;75;74;111;76;76;76;76;104;75 20:06:46 INFO - PROCESS | 2742 | |4;audio-oscillator;83;83;124;82;90;83;111;81;82;122 20:06:46 INFO - PROCESS | 2742 | |5;imaging-gaussian-blur;117;116;117;118;118;118;117;117;117;118 20:06:46 INFO - PROCESS | 2742 | |6;imaging-darkroom;145;145;146;145;146;146;146;146;145;144 20:06:46 INFO - PROCESS | 2742 | |7;imaging-desaturate;84;84;85;84;84;84;84;85;84;84 20:06:46 INFO - PROCESS | 2742 | |8;json-parse-financial;54;54;50;50;51;52;53;53;52;51 20:06:46 INFO - PROCESS | 2742 | |9;json-stringify-tinderbox;58;60;59;59;59;59;60;60;59;60 20:06:46 INFO - PROCESS | 2742 | |10;stanford-crypto-aes;79;81;80;81;79;90;83;81;85;80 20:06:46 INFO - PROCESS | 2742 | |11;stanford-crypto-ccm;133;134;131;132;134;139;130;135;168;130 20:06:46 INFO - PROCESS | 2742 | |12;stanford-crypto-pbkdf2;167;169;166;170;171;164;166;166;167;164 20:06:46 INFO - PROCESS | 2742 | |13;stanford-crypto-sha256-iterative;66;66;65;68;67;64;64;65;64;65 20:06:46 INFO - PROCESS | 2742 | __end_tp_report 20:06:46 INFO - PROCESS | 2742 | __start_cc_report 20:06:46 INFO - PROCESS | 2742 | _x_x_mozilla_cycle_collect,1702 20:06:46 INFO - PROCESS | 2742 | __end_cc_report 20:06:46 INFO - PROCESS | 2742 | __startTimestamp1476760006180__endTimestamp 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | ------- Summary: start ------- 20:06:46 INFO - PROCESS | 2742 | Number of tests: 14 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.67 (2.4%) stddev-sans-first:1.09 20:06:46 INFO - PROCESS | 2742 | Values: 105.0 113.0 114.0 112.0 111.0 114.0 112.0 113.0 114.0 112.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:126.00 stddev:14.45 (11.5%) stddev-sans-first:13.97 20:06:46 INFO - PROCESS | 2742 | Values: 149.0 122.0 123.0 156.0 125.0 125.0 119.0 122.0 153.0 127.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 20:06:46 INFO - PROCESS | 2742 | Values: 160.0 161.0 163.0 162.0 163.0 160.0 161.0 161.0 161.0 161.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:13.61 (17.9%) stddev-sans-first:14.27 20:06:46 INFO - PROCESS | 2742 | Values: 76.0 75.0 74.0 111.0 76.0 76.0 76.0 76.0 104.0 75.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:82.50 stddev:17.67 (21.4%) stddev-sans-first:18.28 20:06:46 INFO - PROCESS | 2742 | Values: 83.0 83.0 124.0 82.0 90.0 83.0 111.0 81.0 82.0 122.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 20:06:46 INFO - PROCESS | 2742 | Values: 117.0 116.0 117.0 118.0 118.0 118.0 117.0 117.0 117.0 118.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 20:06:46 INFO - PROCESS | 2742 | Values: 145.0 145.0 146.0 145.0 146.0 146.0 146.0 146.0 145.0 144.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 20:06:46 INFO - PROCESS | 2742 | Values: 84.0 84.0 85.0 84.0 84.0 84.0 84.0 85.0 84.0 84.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#8] json-parse-financial Cycles:10 Average:52.00 Median:52.50 stddev:1.49 (2.8%) stddev-sans-first:1.39 20:06:46 INFO - PROCESS | 2742 | Values: 54.0 54.0 50.0 50.0 51.0 52.0 53.0 53.0 52.0 51.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.50 stddev:0.67 (1.1%) stddev-sans-first:0.53 20:06:46 INFO - PROCESS | 2742 | Values: 58.0 60.0 59.0 59.0 59.0 59.0 60.0 60.0 59.0 60.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:81.00 stddev:3.38 (4.2%) stddev-sans-first:3.42 20:06:46 INFO - PROCESS | 2742 | Values: 79.0 81.0 80.0 81.0 79.0 90.0 83.0 81.0 85.0 80.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:134.00 stddev:11.35 (8.5%) stddev-sans-first:11.97 20:06:46 INFO - PROCESS | 2742 | Values: 133.0 134.0 131.0 132.0 134.0 139.0 130.0 135.0 168.0 130.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:2.36 (1.4%) stddev-sans-first:2.50 20:06:46 INFO - PROCESS | 2742 | Values: 167.0 169.0 166.0 170.0 171.0 164.0 166.0 166.0 167.0 164.0 20:06:46 INFO - PROCESS | 2742 | 20:06:46 INFO - PROCESS | 2742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:1.35 (2.1%) stddev-sans-first:1.41 20:06:46 INFO - PROCESS | 2742 | Values: 66.0 66.0 65.0 68.0 67.0 64.0 64.0 65.0 64.0 65.0 20:06:46 INFO - PROCESS | 2742 | -------- Summary: end -------- 20:06:46 INFO - PROCESS | 2742 | 20:06:47 INFO - TEST-INFO | 2742: exit 0 20:06:47 INFO - TEST-OK | kraken | took 62136ms 20:06:47 INFO - SUITE-END | took 1180s 20:06:47 INFO - Completed test suite (00:19:40) 20:06:47 INFO - javascript benchmark 20:06:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2954.0547024952016, 2973.8215707057593, 2993.940121197576, 3007.639083310003, 3030.772692048097, 5042.596592272618, 5129.461406552305, 5194.688318700876, 5283.996040752259, 5360.3299587551555, 5174.379074511055, 5197.714125723083, 5272.261883336336, 5293.814716484914, 5351.384590772062, 4718.551737584924, 4719.457262414832, 4770.165221086296, 4789.568938795508, 4791.329213910038, 4803.807847686106, 4835.492273311304, 4874.439439464471, 4876.097921884445, 4939.975300123494, 4634.490206077328, 4805.567498925114, 4817.108834865561, 4864.708117512942, 4896.804127834882, 4661.930071048937, 4725.929111063337, 4733.053389322149, 4773.856784296477, 4790.233562629997, 4742.762861856928, 4746.074515469485, 4753.073150735608, 4808.831690890802, 4816.879578010543, 5241.73791310433, 5244.8688782780355, 5246.449122842085, 5252.159654455307, 5260.947390526084, 5405.783768649249, 5478.45215478453, 5592.384837667853, 5601.075822489284, 5605.0, 5670.376258611549, 5670.914936275959, 5706.857328566777, 5746.856328591819, 5752.194692743019, 5294.047071527124, 5296.973515132477, 5301.946980530222, 5303.28405665238, 5304.443033481506, 5511.090670039479, 5580.804671836466, 5744.080947048452, 5748.913766293509, 5821.18503409523, 5376.354837419534, 5462.071447853795, 5469.535089517356, 5526.336839579196, 5527.751251193706, 5466.480684334982, 5507.476789704971, 5512.862178445571, 5599.608027438122, 5600.411956744565, 5574.0, 5576.414476479992, 5577.414371491016, 5581.49766521015, 5646.971765141148, 5332.520073193431, 5371.328583927009, 5514.228008078872, 5516.91724624131, 5517.917231241535, 4270.08193238449, 4295.656347492193, 4295.897887149845, 4296.914061718748, 4349.521552629209, 3816.1413681921345, 3816.7709937403843, 3827.9808600956817, 3857.7685338879755, 3874.283257597297, 5068.442471328225, 5125.154349532286, 5202.297689811902, 5218.530332270038, 5233.659812112277, 5561.916571251353, 5567.638103523258, 5574.777008919597, 5584.972075139598, 5598.97200514003, 5244.659097158673, 5254.290670759475, 5258.106121959276, 5262.236975638588, 5329.413764485904, 4726.409198850144, 4777.928331075037, 4778.450478194983, 4820.421549414023, 4872.293517439952, 5701.600887937804, 5703.600747947604, 5704.258446401941, 5705.400932902152, 5711.086226203871, 5533.806316779054, 5541.778328866801, 5546.168074788814, 5546.889062218814, 5550.111982082847, 5659.971700141473, 5701.515371193495, 5708.200851880823, 5716.342620598518, 5718.51392631631, 5441.020616289106, 5443.374011988672, 5447.12845944647, 5452.781888724407, 5455.236266922555, 5482.013237617267, 5487.588430867621, 5488.945110549003, 5495.0, 5511.696856672761, 5378.97310513445, 5389.137737961907, 5393.865153371197, 5394.622376433769, 5394.649347792381, 5434.184872269191, 5459.071957767269, 5460.918086228789, 5464.2076898848645, 5465.754041068082, 5208.73956302191, 5258.421573626822, 5408.215808707794, 5411.107167317348, 5462.289902312674, 5454.04554203005, 5496.258005169411, 5499.560035197094, 5536.944630553643, 5600.719964001865, 5393.0, 5412.215228791724, 5414.485623865727, 5415.864603384947, 5416.647917885325], "value": 5140.007626713846, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.4007252855936, 637.1716768201337, 644.2430144580118, 644.6615526848407, 650.9609423434595, 41896.0, 43158.58407610517, 43288.0, 46302.768486157525, 46443.76778116113, 44043.77978110113, 44499.11001779963, 44591.77704111475, 44640.0, 44650.10699786002, 8080.1515840836455, 8698.347623928215, 8870.86693699595, 8871.37900346976, 8881.689140880071, 8031.477953933005, 10402.583896644162, 10629.627963021296, 10645.574177032948, 11014.44927753613, 10787.838182427231, 10846.18653600979, 10849.124981879808, 10854.511546980366, 10857.51141198644, 8078.757637270891, 10657.307275027137, 10657.733556661067, 10669.093127084207, 10739.301945373525, 7917.722879699183, 10110.393376397366, 10412.94793526035, 10439.112675422599, 10771.299865508796, 41151.1769764606, 41420.792896035324, 41509.584904151176, 41533.1693366134, 41534.37698434526, 3430.622631510557, 3432.7253819694365, 3466.618671946084, 3565.286942611488, 3959.2477429288633, 31509.488584748513, 31746.682533174604, 32039.839800800848, 32286.0, 32325.676743232503, 4299.333603291495, 4357.150355680644, 4390.912181756379, 4395.340698895159, 4407.581279778416, 309069.4546527275, 310287.0, 311175.0, 312031.0, 313637.0, 55970.440295597335, 56304.436955630736, 56362.0, 56394.7180264096, 56421.408360578804, 59905.400945990856, 60098.09852852211, 60149.586537211864, 60199.39800601938, 60562.69718651378, 14163.716725665636, 14284.928575357057, 14300.284985750672, 14309.21299328526, 14320.06919550226, 10513.421761803022, 10737.248392612442, 10776.78446431067, 10825.729356766144, 10991.505382257817, 15642.374305027899, 16311.755323670155, 16364.427245046367, 16364.427245046367, 16412.589685257964, 5765.0, 5889.558283128783, 5966.433188847052, 5994.37059108796, 6008.3390827009, 900.6442455230148, 1014.2443879309814, 1024.1601886453186, 1039.599754094679, 1107.7230692326918, 31002.844985775377, 31015.534766978515, 31109.533356999662, 31762.0, 31878.362432751215, 1046.842973553973, 1127.2710977449683, 1130.7512347283584, 1142.9657110286705, 1158.7219067423757, 14931.92534037323, 15834.60413489672, 15896.523104306889, 15911.681766364607, 16121.919390403207, 425465.0, 426897.0, 428920.0, 429738.0, 635113.0, 49284.26073608899, 49805.50194498081, 53556.46443535593, 54371.184432233546, 54559.18161227585, 1281.8461784585909, 1313.6190504753706, 1337.237774468563, 1365.5903229031449, 1374.3883971632526, 29688.109356719335, 31200.0, 31205.219869502536, 31218.843905780326, 31852.0, 49646.25530617116, 50120.0, 50144.74927625339, 50227.0, 50236.246456302455, 393821.0, 395762.0, 396025.0, 396048.0, 397255.0, 26663.067332543542, 26734.465310694068, 26967.190984270503, 27170.72829271682, 27248.863755681887, 45265.54734452613, 45975.0, 46140.7692961533, 46254.0, 46844.765776172266, 37864.242715146094, 39260.80369598134, 39449.40825887556, 40353.79823100866, 41143.0, 38625.2274954505, 39244.60755392409, 39616.80191599139, 39693.60306397015, 40550.59449405468, 39006.6099339003, 39008.414873776346, 39015.0, 39779.801100995464, 40409.797951010056, 31659.0, 34787.30425391428, 35002.0, 35020.82489587536, 35224.0, 26052.478950421264, 26300.473990520466, 26431.867840660674, 26598.65712801256, 26704.465910682065], "value": 19559.75747504875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.4488267598602, 375.25324604037957, 384.1778593809249, 387.1656580069949, 418.91621675664857, 14104.788428173573, 17481.562960925967, 18051.729224061648, 18767.343142989997, 19864.70202946957, 18253.0873456327, 18566.0, 18914.14886330115, 19184.71222931657, 19199.90400047998, 5447.346318441792, 5725.885482290372, 5727.684977326246, 5729.427057294258, 5731.340895796995, 4113.136241389297, 4303.8063287152, 4347.934780978288, 5624.21261023457, 6362.713677884483, 6341.953577659704, 6389.329120442357, 6398.584092034026, 6404.487640988733, 6415.67921603918, 6074.605150665215, 6258.968705156468, 6284.968575157141, 6288.308286088527, 6293.3391993840905, 5034.270030845543, 5282.630215884889, 5522.834314970517, 5524.806631767909, 5525.723713814293, 11632.243904146204, 11784.234024788448, 11802.11484138693, 11808.0, 12205.877941220562, 2140.743110826695, 2143.9035243414082, 2145.3349461666776, 2192.539566690981, 2242.6411774116227, 8897.199252067345, 9699.563519641633, 10066.69799906004, 10238.846417303746, 10364.222683298784, 1931.9323823666105, 1934.2359767891744, 1945.387203031055, 1946.2020571565731, 1977.2881762565464, 77692.99999999943, 78431.0, 79029.60485197537, 80497.59751201265, 84737.15262847354, 10086.293959422917, 10343.0, 10419.270651054505, 10464.633737819293, 10474.423906685053, 14480.710385792225, 14655.780163297559, 14664.560063198121, 15175.696486070217, 15203.619909502351, 7658.234176582298, 7775.345587573831, 7910.5649189295145, 7928.1675424080795, 7987.56068416231, 6951.756688515878, 7048.577085374894, 7205.2434494378385, 7224.747133850396, 7573.924260757432, 4394.406755088086, 4539.601884512002, 4572.977135114304, 4636.890214952188, 4650.95349046512, 1892.858035647332, 1896.7723873135312, 1902.6955687089999, 1950.0834607734341, 1967.0722691762153, 790.3874497264551, 831.6216121664628, 907.2832462354797, 913.1142791492242, 997.0577803975174, 17639.823601764074, 17995.190216440293, 18453.446396608124, 18481.630367392576, 19101.713474297896, 918.4213945214472, 934.289939645874, 955.9665411710557, 956.0821611253136, 970.9611615535299, 9650.131488165958, 10282.280240383101, 10295.0, 10338.435306713247, 10395.376277423227, 432919.0, 447898.0, 448608.0, 449040.0, 453870.0, 89580.5520972404, 89680.0, 90413.54793225991, 90578.54710726404, 92369.53815231015, 923.7044145873256, 925.3013974449248, 946.7207173883721, 953.2802733935835, 955.4840386191379, 15716.371345146066, 15896.920515397349, 16567.66864662676, 16593.751093733845, 16733.0, 38699.806500967316, 40055.39916901307, 40317.39523907202, 40401.595984039785, 40719.79640101899, 53854.73072634612, 55891.44108559025, 56967.0, 57123.714381427824, 58298.41701583099, 8035.718749843727, 8380.53907035119, 8394.160583941557, 8499.065102838558, 8619.310455163697, 10762.784744304914, 12381.75236495283, 13332.133411328232, 13545.729085418434, 13659.248741319323, 11780.882191177978, 11960.820587691365, 12000.219985700902, 12382.81425778596, 12412.317322547347, 11356.274494121517, 11903.16677832578, 11933.701657458632, 11968.341741204318, 12528.37358132108, 11654.30074195551, 11932.70168245766, 12254.448549815457, 12319.938400307941, 12380.62858114258, 8407.453515521716, 8538.10349913275, 8654.177853103945, 8674.306055515417, 8770.166834150745, 10187.0, 11534.480948357177, 11643.41782910834, 11673.474693638636, 12003.1597788154], "value": 8785.834884361073, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.2618964328192, 1509.8037255156828, 1616.854483096521, 1757.534253422844, 1769.4072485717293, 7943.880841787378, 8182.713605023825, 8354.039285482171, 9006.954965225174, 9011.459312441259, 8406.453580517278, 8728.345374096953, 8732.95633521833, 8861.069587693297, 8871.822563548725, 2207.5253820428607, 2234.1845226492296, 2240.406292332533, 2243.562505311465, 2252.718410198725, 1927.2291083566545, 1992.1334219614441, 2183.9890800546054, 2213.612617791888, 2237.5860465813794, 2122.182959560565, 2184.541246338271, 2186.8031877131057, 2192.6820611011394, 2199.7910198531113, 2195.549912267997, 2211.524522227721, 2217.5897458970144, 2218.7559368469456, 2220.0453804863823, 2162.924297649575, 2175.4126385875725, 2182.1489619048584, 2183.858049226795, 2193.3419974007893, 8834.293256539462, 8950.373473856833, 8955.910440895574, 8957.776055598597, 8960.327975401871, 5034.773435195425, 5201.9479805201845, 5208.5572726318305, 5214.0, 5218.8695282617855, 3206.8556914938886, 3381.070205693429, 3427.622961474236, 3456.2741824216946, 3469.930601387983, 948.3551185194066, 993.5479356892669, 996.5515518016849, 1011.585250047477, 1012.691129205592, 12273.693157671041, 12373.505059797688, 12382.566610168691, 12387.814182787266, 12398.938005309916, 4291.935620965688, 4360.8909777255185, 4507.5267096955, 4544.272916333437, 4551.658625603093, 10258.28192026551, 10262.692119236435, 10271.537780799881, 10282.845757313646, 10303.227257955683, 5208.192730126836, 5209.47905209476, 5232.764525596357, 5234.450382709761, 5235.685858848481, 4590.104929538775, 4623.606993405531, 4811.374521312207, 4812.037592481518, 4816.229403295456, 3708.332500149999, 3711.3319602471274, 3725.18046029873, 3796.7152463565344, 3812.370958791824, 1099.5162128663362, 1122.7305446692735, 1164.3596022187762, 1169.6607983684637, 1177.1642134084896, 847.7753395350237, 879.6657270237268, 931.2038207332769, 931.4969916245152, 954.8424509955919, 5836.503897168704, 5878.500327472213, 5899.410058994066, 5975.790847320323, 6224.315325314212, 848.1179573243758, 852.9530875801767, 864.2740098317444, 912.1243606138181, 938.2353381993653, 3800.201957588882, 3860.7104467165077, 3888.766673999569, 4043.6765058795227, 4051.0480037191824, 10184.490775461198, 10390.0, 10489.475526223658, 10520.26358154937, 10723.94638026805, 10504.894951050392, 10660.360378377321, 10669.306495077795, 10670.199735019905, 10679.359238445719, 8187.385946053951, 8352.373571982003, 8404.243618074403, 8407.53758543286, 8419.579021049045, 5933.792317268875, 5961.314448838439, 5997.610155339889, 6068.4841788448475, 6151.231096112986, 9723.222142228746, 10317.071463568314, 10329.586816527555, 10396.428196448966, 10473.581056757645, 10521.894781052091, 10569.365838049742, 10580.576776928836, 10598.15214782831, 10602.310849794738, 7966.522008679498, 8056.637451314587, 8061.717839875576, 8075.434719569574, 8087.514749115072, 8104.311133553478, 8161.347092232726, 8266.958665206635, 8267.793305167419, 8291.792705182177, 7661.118971318369, 7674.6930122794465, 7702.922970770221, 7805.765827025199, 7911.683532658629, 7450.329470347695, 7555.6977720890545, 7716.263840636037, 7766.88349674744, 7820.452568320163, 6989.30106989297, 7008.894866576903, 7027.7188912442925, 7040.239940014996, 7046.894296585453, 5000.974995125001, 5027.773750181177, 5073.543381095719, 5118.181091025418, 5142.820001299937, 5128.487151284841, 5187.792488300426, 5291.074062039204, 5331.493508116722, 5378.569714422915], "value": 4544.956791202603, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.925052612636, 319.2609109910557, 325.28275153286995, 331.491161067761, 332.56600136821436, 5107.259447380127, 5240.580753539717, 5287.41838397776, 5355.143177091666, 5355.830888490163, 5073.162928116856, 5184.611154163434, 5205.141151709963, 5215.217717342401, 5227.555657769085, 4084.2852500812387, 4107.17856428714, 4109.342505199169, 4116.238495878265, 4120.381942708587, 3920.8823735287842, 4023.3763766616075, 4118.567550407209, 4119.526254480743, 4150.43969064177, 4035.1122752994293, 4038.3538633818594, 4039.4142849286836, 4051.7642119153793, 4056.574059723731, 4006.419069234974, 4027.2750904837117, 4034.7780872052026, 4040.8585699500377, 4042.3734321180264, 3572.1426857554, 3572.8749493767596, 3573.535440392758, 3575.785452872836, 3596.946045809315, 5023.673461225008, 5077.517635824591, 5080.1617733074, 5087.804365973993, 5136.537711605936, 4616.0, 4629.916537558079, 4630.629549636021, 4650.813967441299, 4681.812727490897, 5493.533049690741, 5509.0, 5528.364238112628, 5535.916961245584, 5542.251796007527, 3795.7532760370486, 3897.7466464679705, 3903.394973779069, 3908.6743387684282, 3913.256481268578, 5579.944200557983, 5621.80323688669, 5623.862932085822, 5639.661620302795, 5656.971715141439, 3974.463447434617, 3980.1641655252142, 3982.1682026267663, 3984.541777695544, 4022.718409711292, 3839.1745774658584, 4027.509821366113, 4033.1127152026506, 4070.776107314126, 4074.7351422157467, 4607.631389488833, 4610.723356598614, 4612.846788302924, 4620.214563524209, 4627.398438203012, 5511.118221084607, 5511.22842802008, 5513.558915286768, 5513.779448822002, 5522.502974732293, 1325.5426877727361, 1342.161149281699, 1363.8704323089291, 1392.2551434982438, 1392.8746412822895, 934.1545900959643, 940.8776859008285, 941.6280569175275, 958.6453012385462, 973.2116985241977, 5247.9737601311745, 5248.527632513092, 5254.710990895537, 5257.474252574789, 5333.306670132935, 5559.916601250984, 5571.8240602855985, 5584.664920104807, 5619.044762390404, 5644.57665675076, 5083.339165908482, 5230.241614965808, 5233.215017747292, 5249.868753281198, 5332.413434522199, 2963.074001898783, 2985.268609190762, 2989.7458716008946, 2998.6551546571986, 3004.444177827109, 5834.620749651259, 5863.0, 5865.325487568899, 5901.321348044944, 5902.439268269508, 5559.193916882029, 5729.914051289234, 5740.425957404226, 5750.591105179258, 5793.479836271151, 5779.971100144472, 5795.420457954171, 5824.0, 5835.978703726916, 5877.4122587742595, 5382.7308634567335, 5579.9163012555655, 5602.5517958562405, 5729.427057294237, 5735.0, 5553.111502159797, 5564.19319198722, 5595.384507704231, 5595.608307418442, 5597.412271711411, 5808.39011903744, 5831.883362332815, 5839.883202336015, 5863.296404431496, 5904.3210030845285, 3859.5333773166017, 3946.9210615788097, 3960.8315546914305, 3964.544077431017, 3980.820863061111, 5291.9470805292995, 5567.331920169444, 5581.190727344431, 5603.439656034364, 5618.831435056954, 5350.261165121274, 5384.111621582395, 5519.613627046068, 5541.473560011793, 5582.804601838916, 5460.972695136499, 5528.834134975958, 5530.944690553043, 5550.972245138749, 5576.86057848557, 5272.420033796356, 5422.755975981011, 5424.2134890439875, 5485.149801780731, 5489.286392768914, 5507.686771556037, 5538.255449533384, 5543.861403464945, 5550.47270509301, 5553.527950124285, 5403.838174792439, 5465.262189604353, 5493.505584497414, 5500.669959802424, 5505.752241149238], "value": 4181.157756105353, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.942289905358, 696.2202333386608, 731.7987553422809, 745.1468069060926, 825.7976795685056, 15693.686126277482, 16269.755953660704, 16337.509874703763, 16353.672926541461, 16359.182040897967, 14481.0, 14574.906881983841, 14616.707665846676, 14623.561293161221, 14623.92688036561, 641.5477088652489, 665.9134312539363, 773.0800347586382, 806.0689903161842, 810.2181394953868, 798.0423491809822, 799.9120096789349, 800.3317230112863, 800.3437181511163, 803.2931020701803, 871.0636066228798, 873.8164985353084, 874.6501399440211, 874.7550685807984, 874.8731433942074, 678.145536623855, 710.1620088295809, 792.3067316098413, 844.1769274956929, 867.0679020053468, 625.2184769038702, 628.2680677011276, 628.6416742456802, 629.2291942370605, 629.3769168523174, 14722.631934201623, 14800.259987000607, 14805.925970370079, 14823.481178158712, 14828.258587070712, 13813.723725525433, 14095.295235238196, 14354.282285885663, 14468.421263149461, 14473.276336183255, 12708.87291127086, 12777.361131943459, 12966.80549791754, 13201.0, 13501.729965400735, 4252.425922500453, 4314.346567636957, 4383.583559561868, 4384.605385515287, 4421.9336709949375, 79694.60152699257, 80781.59609201975, 81012.59493702553, 81541.0, 81594.0, 11005.77758535753, 11193.216474846846, 11208.103351732005, 11431.714207144722, 11897.0, 21374.0, 21485.46286342854, 21731.130754769947, 21739.45651358697, 21746.78253217479, 8096.149904259968, 8219.383546234056, 8241.79395515117, 8289.668413263402, 8290.37822163328, 4677.158111539956, 4695.906081878412, 4703.764811759398, 4798.832040878552, 4800.591949684246, 3319.3195394943978, 3398.337324221753, 3399.66003399663, 3413.197898493852, 3420.0936751760805, 572.1475002246706, 580.8083332500349, 593.679413116912, 598.7305712429337, 613.9631622102688, 584.3659629302169, 647.2653538234138, 654.3750718064238, 677.932206779328, 695.1310861423221, 8603.440776349642, 8967.327450441244, 8980.8652870207, 8982.685606003759, 9017.368784185175, 537.3256563013393, 587.538782056084, 633.8859005378984, 650.8015055408146, 661.0315887225238, 4032.206119162641, 4190.0, 4236.173946080485, 4330.675199359997, 4410.514843367227, 116256.0, 120477.39761301137, 120523.39738301428, 121047.0, 122871.0, 83789.0, 100941.44822034858, 102172.0, 104094.47952760187, 104431.0, 54073.72963135159, 57266.7136664314, 78760.60619696864, 80506.5974670123, 83782.0, 8962.283017358464, 9372.67195648149, 9547.904520954702, 9649.372790768577, 9693.22454203648, 36427.45358819673, 36532.63467365292, 46683.53316466792, 46703.766481167375, 47467.28799068086, 44439.0, 48962.51037489579, 49363.50636493589, 63441.0, 73573.0, 12951.288256795131, 13045.152065115737, 13142.80285795733, 13242.933785331012, 13258.204507729568, 13852.653683657989, 13860.237686927316, 14182.361793719101, 14395.136291822524, 14445.638859028188, 13724.725505489634, 13905.930470347583, 14896.702065958836, 14979.700405991602, 14981.0, 13526.797098043735, 14112.29438528049, 14366.856331436551, 14396.928015359856, 14880.255987200813, 13154.092656564935, 13579.52471663487, 13632.522861699794, 13884.51404200848, 13907.443702252202, 10998.835017474905, 11151.888481115086, 11312.886871131182, 11326.37704926204, 11508.925999145475, 8948.328875334506, 9306.813863722824, 9513.286503512127, 10429.804552976442, 10910.084179682714], "value": 7327.421729061542, "unit": "score"}], "name": "dromaeo_css", "value": 7052.160365703483, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 114.0, 112.0, 111.0, 114.0, 112.0, 113.0, 114.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 123.0, 156.0, 125.0, 125.0, 119.0, 122.0, 153.0, 127.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 162.0, 163.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 111.0, 76.0, 76.0, 76.0, 76.0, 104.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 124.0, 82.0, 90.0, 83.0, 111.0, 81.0, 82.0, 122.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 144.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 85.0, 84.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 50.0, 50.0, 51.0, 52.0, 53.0, 53.0, 52.0, 51.0], "value": 52.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 59.0, 59.0, 59.0, 60.0, 60.0, 59.0, 60.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 80.0, 81.0, 79.0, 90.0, 83.0, 81.0, 85.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 131.0, 132.0, 134.0, 139.0, 130.0, 135.0, 168.0, 130.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 166.0, 170.0, 171.0, 164.0, 166.0, 166.0, 167.0, 164.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 68.0, 67.0, 64.0, 64.0, 65.0, 64.0, 65.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1490.5, "alertThreshold": 2.0}]} 20:06:47 INFO - Return code: 0 20:06:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:06:47 INFO - # TBPL SUCCESS # 20:06:47 INFO - Running post-action listener: _resource_record_post_action 20:06:47 INFO - [mozharness: 2016-10-18 03:06:47.985241Z] Finished run-tests step (success) 20:06:47 INFO - Running post-run listener: _resource_record_post_run 20:06:48 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 393052160; Read time: 0; Write time: 384628 20:06:48 INFO - TinderboxPrint: CPU usage
13.0% 20:06:48 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 20:06:48 INFO - TinderboxPrint: I/O write bytes / time
393,052,160 / 384,628 20:06:48 INFO - TinderboxPrint: CPU idle
8,310.3 (86.9%) 20:06:48 INFO - TinderboxPrint: CPU user
1,197.4 (12.5%) 20:06:48 INFO - TinderboxPrint: Swap in / out
0 / 0 20:06:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105041920; Read time: 0; Write time: 118884 20:06:48 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 287526912; Read time: 0; Write time: 265740 20:06:48 INFO - Running post-run listener: _upload_blobber_files 20:06:48 INFO - Blob upload gear active. 20:06:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:06:48 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 20:06:48 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:06:48 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:06:49 INFO - (blobuploader) - INFO - Open directory for files ... 20:06:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:06:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:06:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:49 INFO - (blobuploader) - INFO - Done attempting. 20:06:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:06:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:06:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:52 INFO - (blobuploader) - INFO - Done attempting. 20:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:06:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:06:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:06:53 INFO - (blobuploader) - INFO - Done attempting. 20:06:53 INFO - (blobuploader) - INFO - Iteration through files over. 20:06:53 INFO - Return code: 0 20:06:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:06:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:06:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce2ba2a3f10767d0c8c7bd47c0f6db6604ac030279fe092ceb336e1a6b5321dfe1a3541276a8422a71adbff82e3cbe7c67ba3bdd843c0f9e2c77182b72c92905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29e38b3b090cb1319f2be229f301bb6a2209ee355269afa60709c77cf3cd3ec691f8f2c81725ed6405edd7b2da295ea2925f07c0d4ce975311e5b28a88b2f676", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25c8d6ee397ff8daf2ce83b6868af8ba1a2959aa34c25c356d7fe0d86a3a7eb6d0434295a509047bf3784bcdd516954361717e13c3c5aa37cd031163a8593c29"} 20:06:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:06:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:06:53 INFO - Contents: 20:06:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce2ba2a3f10767d0c8c7bd47c0f6db6604ac030279fe092ceb336e1a6b5321dfe1a3541276a8422a71adbff82e3cbe7c67ba3bdd843c0f9e2c77182b72c92905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29e38b3b090cb1319f2be229f301bb6a2209ee355269afa60709c77cf3cd3ec691f8f2c81725ed6405edd7b2da295ea2925f07c0d4ce975311e5b28a88b2f676", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25c8d6ee397ff8daf2ce83b6868af8ba1a2959aa34c25c356d7fe0d86a3a7eb6d0434295a509047bf3784bcdd516954361717e13c3c5aa37cd031163a8593c29"} 20:06:53 INFO - Running post-run listener: copy_logs_to_upload_dir 20:06:53 INFO - Copying logs to upload dir... 20:06:53 INFO - mkdir: /builds/slave/test/build/upload/logs 20:06:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.198188 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-10-17 20:06:53.229228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 20:06:53.232714) ========= 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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce2ba2a3f10767d0c8c7bd47c0f6db6604ac030279fe092ceb336e1a6b5321dfe1a3541276a8422a71adbff82e3cbe7c67ba3bdd843c0f9e2c77182b72c92905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29e38b3b090cb1319f2be229f301bb6a2209ee355269afa60709c77cf3cd3ec691f8f2c81725ed6405edd7b2da295ea2925f07c0d4ce975311e5b28a88b2f676", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25c8d6ee397ff8daf2ce83b6868af8ba1a2959aa34c25c356d7fe0d86a3a7eb6d0434295a509047bf3784bcdd516954361717e13c3c5aa37cd031163a8593c29"} build_url:https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011349 build_url: 'https://queue.taskcluster.net/v1/task/X0eSPYdSThmcH0R4NuaQOg/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/Firefox/sha512/ce2ba2a3f10767d0c8c7bd47c0f6db6604ac030279fe092ceb336e1a6b5321dfe1a3541276a8422a71adbff82e3cbe7c67ba3bdd843c0f9e2c77182b72c92905", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29e38b3b090cb1319f2be229f301bb6a2209ee355269afa60709c77cf3cd3ec691f8f2c81725ed6405edd7b2da295ea2925f07c0d4ce975311e5b28a88b2f676", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25c8d6ee397ff8daf2ce83b6868af8ba1a2959aa34c25c356d7fe0d86a3a7eb6d0434295a509047bf3784bcdd516954361717e13c3c5aa37cd031163a8593c29"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-17 20:06:53.258660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 20:06:53.258996) ========= 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=eca2ab57546de7b41f256b19000001ef-1476758770.81711-1107156045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.19 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-17 20:06:53.449190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 20:06:53.449522) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-17 20:06:53.449836) ========= ========= Total master_lag: 0.28 =========