builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1476505455.01 results: success (0) buildid: 20161014212334 builduid: 626b513b43614fefa0fe3337b668f34a revision: 3c83a34f913ad840252fba1eda31d656388179c4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.006080) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.006415) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.006686) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.024106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.024489) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-14 21:24:15-- 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% 70.5M=0s 2016-10-14 21:24:15 (70.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.207271 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.244949) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.245224) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020232 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:15.294050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 21:24:15.294347) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 3c83a34f913ad840252fba1eda31d656388179c4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 3c83a34f913ad840252fba1eda31d656388179c4 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-14 21:24:15,499 truncating revision to first 12 chars 2016-10-14 21:24:15,499 Setting DEBUG logging. 2016-10-14 21:24:15,499 attempt 1/10 2016-10-14 21:24:15,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/3c83a34f913a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 21:24:16,398 unpacking tar archive at: mozilla-inbound-3c83a34f913a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.226675 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 21:24:16.538061) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:16.538657) ========= script_repo_revision: 3c83a34f913ad840252fba1eda31d656388179c4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:16.539054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:16.539625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 21:24:16.550610) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-10-14 21:24:16.551183) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:24:16 INFO - MultiFileLogger online at 20161014 21:24:16 in /builds/slave/test 21:24:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 21:24:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:24:16 INFO - {'append_to_log': False, 21:24:16 INFO - 'base_work_dir': '/builds/slave/test', 21:24:16 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 21:24:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:24:16 INFO - 'branch': 'Mozilla-Inbound', 21:24:16 INFO - 'buildbot_json_path': 'buildprops.json', 21:24:16 INFO - 'config_files': ('talos/linux_config.py',), 21:24:16 INFO - 'default_actions': ('clobber', 21:24:16 INFO - 'read-buildbot-config', 21:24:16 INFO - 'download-and-extract', 21:24:16 INFO - 'populate-webroot', 21:24:16 INFO - 'create-virtualenv', 21:24:16 INFO - 'install', 21:24:16 INFO - 'run-tests'), 21:24:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:24:16 INFO - 'download_minidump_stackwalk': True, 21:24:16 INFO - 'download_symbols': 'ondemand', 21:24:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:24:16 INFO - 'tooltool.py': '/tools/tooltool.py', 21:24:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:24:16 INFO - '/tools/misc-python/virtualenv.py')}, 21:24:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:24:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:24:16 INFO - 'installer_path': 'installer.exe', 21:24:16 INFO - 'log_level': 'info', 21:24:16 INFO - 'log_name': 'talos', 21:24:16 INFO - 'log_to_console': True, 21:24:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:24:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:24:16 INFO - 'opt_config_files': (), 21:24:16 INFO - 'pip_index': False, 21:24:16 INFO - 'sps_profile': False, 21:24:16 INFO - 'sps_profile_interval': 0, 21:24:16 INFO - 'suite': 'dromaeojs', 21:24:16 INFO - 'system_bits': '32', 21:24:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:24:16 INFO - 'title': 'talos-linux64-ix-031', 21:24:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:24:16 INFO - 'use_talos_json': True, 21:24:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:24:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:24:16 INFO - 'work_dir': 'build'} 21:24:16 INFO - [mozharness: 2016-10-15 04:24:16.862613Z] Running clobber step. 21:24:16 INFO - Running pre-action listener: _resource_record_pre_action 21:24:16 INFO - Running main action method: clobber 21:24:16 INFO - rmtree: /builds/slave/test/build 21:24:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:24:17 INFO - Running post-action listener: _resource_record_post_action 21:24:17 INFO - [mozharness: 2016-10-15 04:24:17.845891Z] Finished clobber step (success) 21:24:17 INFO - [mozharness: 2016-10-15 04:24:17.845988Z] Running read-buildbot-config step. 21:24:17 INFO - Running pre-action listener: _resource_record_pre_action 21:24:17 INFO - Running main action method: read_buildbot_config 21:24:17 INFO - Using buildbot properties: 21:24:17 INFO - { 21:24:17 INFO - "project": "", 21:24:17 INFO - "product": "firefox", 21:24:17 INFO - "script_repo_revision": "production", 21:24:17 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 21:24:17 INFO - "repository": "", 21:24:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 21:24:17 INFO - "buildid": "20161014212334", 21:24:17 INFO - "stage_platform": "linux64-pgo", 21:24:17 INFO - "basedir": "/builds/slave/test", 21:24:17 INFO - "buildnumber": 1835, 21:24:17 INFO - "platform": "ubuntu64_hw", 21:24:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:24:17 INFO - "slavebuilddir": "test", 21:24:17 INFO - "branch": "mozilla-inbound", 21:24:17 INFO - "revision": "3c83a34f913ad840252fba1eda31d656388179c4", 21:24:17 INFO - "slavename": "talos-linux64-ix-031", 21:24:17 INFO - "builduid": "626b513b43614fefa0fe3337b668f34a", 21:24:17 INFO - "repo_path": "integration/mozilla-inbound" 21:24:17 INFO - } 21:24:17 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 21:24:17 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 21:24:17 INFO - Running post-action listener: _resource_record_post_action 21:24:17 INFO - [mozharness: 2016-10-15 04:24:17.848307Z] Finished read-buildbot-config step (success) 21:24:17 INFO - [mozharness: 2016-10-15 04:24:17.848395Z] Running download-and-extract step. 21:24:17 INFO - Running pre-action listener: _resource_record_pre_action 21:24:17 INFO - Running main action method: download_and_extract 21:24:17 INFO - mkdir: /builds/slave/test/build/tests 21:24:17 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')]} 21:24:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:24:17 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/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 21:24:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/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 21:24:17 INFO - Downloaded 1361 bytes. 21:24:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 21:24:17 INFO - Using the following test package requirements: 21:24:17 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:24:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:24:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:24:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'jsshell-linux-x86_64.zip'], 21:24:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:24:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 21:24:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:24:17 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:24:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:24:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 21:24:17 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:24:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:24:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:24:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:24:17 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:24:17 INFO - Http code: 200 21:24:17 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 21:24:17 INFO - x-amz-cf-id: 3W2po--O0HXYr5BRxIaFHwo-Xs3dx1SkLREf7XmNnYS_wFiUFSyc5A== 21:24:17 INFO - x-amz-replication-status: COMPLETED 21:24:17 INFO - x-amz-version-id: nRqEEuGOHOHkQLPxuxJoUSk4K9Ff4uuv 21:24:18 INFO - Expected file size: 22117008 21:24:18 INFO - Obtained file size: 22117008 21:24:22 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 21:24:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 21:24:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:24:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:24:22 INFO - Http code: 200 21:24:22 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 21:24:22 INFO - x-amz-cf-id: 1smb5lxQUquoTFiC8Aci3lclgGm2NFI0DDpFWnXCeYjB2otbm_N9jg== 21:24:22 INFO - x-amz-replication-status: COMPLETED 21:24:22 INFO - x-amz-version-id: nRqEEuGOHOHkQLPxuxJoUSk4K9Ff4uuv 21:24:22 INFO - Expected file size: 22117008 21:24:22 INFO - Obtained file size: 22117008 21:24:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 21:24:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:24:27 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:24:27 INFO - Http code: 200 21:24:27 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 21:24:27 INFO - x-amz-cf-id: s0GU8Ppv5lICHH7_yF09pIko06tvw6SaQUNCqNwbGjCyvPztXUXwgA== 21:24:27 INFO - x-amz-replication-status: COMPLETED 21:24:27 INFO - x-amz-version-id: v.keMHdKXdTQb.s9MBxI02yde.p7UTwH 21:24:27 INFO - Expected file size: 14409028 21:24:27 INFO - Obtained file size: 14409028 21:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:27 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:24:27 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/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 21:24:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/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 21:24:28 INFO - Downloaded 59618052 bytes. 21:24:28 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:24:28 INFO - mkdir: /builds/slave/test/properties 21:24:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:24:28 INFO - Writing to file /builds/slave/test/properties/build_url 21:24:28 INFO - Contents: 21:24:28 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 21:24:28 INFO - Running post-action listener: _resource_record_post_action 21:24:28 INFO - Running post-action listener: set_extra_try_arguments 21:24:28 INFO - [mozharness: 2016-10-15 04:24:28.758619Z] Finished download-and-extract step (success) 21:24:28 INFO - [mozharness: 2016-10-15 04:24:28.758844Z] Running populate-webroot step. 21:24:28 INFO - Running pre-action listener: _resource_record_pre_action 21:24:28 INFO - Running main action method: populate_webroot 21:24:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:24:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:24:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:24:28 INFO - '--tppagecycles', 21:24:28 INFO - '7'], 21:24:28 INFO - 'tests': ['tsvgm']}}, 21:24:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:24:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:24:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:24:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:24:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:24:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:24:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['damp', 'tps']}, 21:24:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'tests': ['damp', 'tps']}, 21:24:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['dromaeo_dom']}, 21:24:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:24:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['basic_compositor_video']}, 21:24:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:24:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'other-e10s': {'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['a11yr', 21:24:28 INFO - 'ts_paint', 21:24:28 INFO - 'tpaint', 21:24:28 INFO - 'sessionrestore', 21:24:28 INFO - 'sessionrestore_no_auto_restore', 21:24:28 INFO - 'tabpaint']}, 21:24:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['tsvgx', 21:24:28 INFO - 'tsvgr_opacity', 21:24:28 INFO - 'tart', 21:24:28 INFO - 'tscrollx', 21:24:28 INFO - 'cart']}, 21:24:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:24:28 INFO - 'tsvgr_opacity', 21:24:28 INFO - 'tart', 21:24:28 INFO - 'tscrollx', 21:24:28 INFO - 'cart']}, 21:24:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'talos_options': ['--disable-e10s'], 21:24:28 INFO - 'tests': ['tp5o']}, 21:24:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'tests': ['tp5o']}, 21:24:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'talos_options': ['--disable-e10s', 21:24:28 INFO - '--xperf_path', 21:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:24:28 INFO - 'tests': ['tp5n']}, 21:24:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:24:28 INFO - 'talos_options': ['--xperf_path', 21:24:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:24:28 INFO - 'tests': ['tp5n']}}, 21:24:28 INFO - 'talos.zip': {'path': '', 21:24:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:24:28 INFO - Running post-action listener: _resource_record_post_action 21:24:28 INFO - [mozharness: 2016-10-15 04:24:28.781116Z] Finished populate-webroot step (success) 21:24:28 INFO - [mozharness: 2016-10-15 04:24:28.781203Z] Running create-virtualenv step. 21:24:28 INFO - Running pre-action listener: _resource_record_pre_action 21:24:28 INFO - Running main action method: create_virtualenv 21:24:28 INFO - Creating virtualenv /builds/slave/test/build/venv 21:24:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:24:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:24:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:24:28 INFO - Using real prefix '/usr' 21:24:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:24:29 INFO - Installing distribute.............................................................................................................................................................................................done. 21:24:31 INFO - Installing pip.................done. 21:24:31 INFO - Return code: 0 21:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:24:31 INFO - Reading from file tmpfile_stdout 21:24:31 INFO - Output received: 21:24:31 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) 21:24:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:24:31 INFO - Using env: {'DISPLAY': ':0', 21:24:31 INFO - 'HOME': '/home/cltbld', 21:24:31 INFO - 'LANG': 'en_US.UTF-8', 21:24:31 INFO - 'LANGUAGE': 'en_US:en', 21:24:31 INFO - 'LOGNAME': 'cltbld', 21:24:31 INFO - 'MAIL': '/var/mail/cltbld', 21:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:31 INFO - 'MOZ_NO_REMOTE': '1', 21:24:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:31 INFO - 'NO_EM_RESTART': '1', 21:24:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:31 INFO - 'PWD': '/builds/slave/test', 21:24:31 INFO - 'SHELL': '/bin/bash', 21:24:31 INFO - 'SHLVL': '1', 21:24:31 INFO - 'TERM': 'linux', 21:24:31 INFO - 'TMOUT': '86400', 21:24:31 INFO - 'USER': 'cltbld', 21:24:31 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:31 INFO - '_': '/tools/buildbot/bin/python'} 21:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:31 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 21:24:31 INFO - Cleaning up... 21:24:31 INFO - Return code: 0 21:24:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:24:31 INFO - Using env: {'DISPLAY': ':0', 21:24:31 INFO - 'HOME': '/home/cltbld', 21:24:31 INFO - 'LANG': 'en_US.UTF-8', 21:24:31 INFO - 'LANGUAGE': 'en_US:en', 21:24:31 INFO - 'LOGNAME': 'cltbld', 21:24:31 INFO - 'MAIL': '/var/mail/cltbld', 21:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:31 INFO - 'MOZ_NO_REMOTE': '1', 21:24:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:31 INFO - 'NO_EM_RESTART': '1', 21:24:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:31 INFO - 'PWD': '/builds/slave/test', 21:24:31 INFO - 'SHELL': '/bin/bash', 21:24:31 INFO - 'SHLVL': '1', 21:24:31 INFO - 'TERM': 'linux', 21:24:31 INFO - 'TMOUT': '86400', 21:24:31 INFO - 'USER': 'cltbld', 21:24:31 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:31 INFO - '_': '/tools/buildbot/bin/python'} 21:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:32 INFO - Downloading/unpacking psutil>=3.1.1 21:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:24:34 INFO - Installing collected packages: psutil 21:24:34 INFO - Running setup.py install for psutil 21:24:34 INFO - building 'psutil._psutil_linux' extension 21:24:34 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 21:24:34 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 21:24:34 INFO - building 'psutil._psutil_posix' extension 21:24:34 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 21:24:34 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 21:24:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:24:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:24:34 INFO - Successfully installed psutil 21:24:34 INFO - Cleaning up... 21:24:34 INFO - Return code: 0 21:24:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:24:34 INFO - Using env: {'DISPLAY': ':0', 21:24:34 INFO - 'HOME': '/home/cltbld', 21:24:34 INFO - 'LANG': 'en_US.UTF-8', 21:24:34 INFO - 'LANGUAGE': 'en_US:en', 21:24:34 INFO - 'LOGNAME': 'cltbld', 21:24:34 INFO - 'MAIL': '/var/mail/cltbld', 21:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:34 INFO - 'MOZ_NO_REMOTE': '1', 21:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:34 INFO - 'NO_EM_RESTART': '1', 21:24:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:34 INFO - 'PWD': '/builds/slave/test', 21:24:34 INFO - 'SHELL': '/bin/bash', 21:24:34 INFO - 'SHLVL': '1', 21:24:34 INFO - 'TERM': 'linux', 21:24:34 INFO - 'TMOUT': '86400', 21:24:34 INFO - 'USER': 'cltbld', 21:24:34 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:34 INFO - '_': '/tools/buildbot/bin/python'} 21:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:24:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:24:36 INFO - Installing collected packages: mozsystemmonitor 21:24:36 INFO - Running setup.py install for mozsystemmonitor 21:24:36 INFO - Successfully installed mozsystemmonitor 21:24:36 INFO - Cleaning up... 21:24:36 INFO - Return code: 0 21:24:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:24:36 INFO - Using env: {'DISPLAY': ':0', 21:24:36 INFO - 'HOME': '/home/cltbld', 21:24:36 INFO - 'LANG': 'en_US.UTF-8', 21:24:36 INFO - 'LANGUAGE': 'en_US:en', 21:24:36 INFO - 'LOGNAME': 'cltbld', 21:24:36 INFO - 'MAIL': '/var/mail/cltbld', 21:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:36 INFO - 'MOZ_NO_REMOTE': '1', 21:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:36 INFO - 'NO_EM_RESTART': '1', 21:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:36 INFO - 'PWD': '/builds/slave/test', 21:24:36 INFO - 'SHELL': '/bin/bash', 21:24:36 INFO - 'SHLVL': '1', 21:24:36 INFO - 'TERM': 'linux', 21:24:36 INFO - 'TMOUT': '86400', 21:24:36 INFO - 'USER': 'cltbld', 21:24:36 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:36 INFO - '_': '/tools/buildbot/bin/python'} 21:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:37 INFO - Downloading/unpacking jsonschema==2.5.1 21:24: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 21:24: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 21:24: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 21:24: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 21:24: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 21:24: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 21:24:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:24:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:24:39 INFO - Downloading functools32-3.2.3-2.tar.gz 21:24:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:24:39 INFO - warning: no files found matching '*.txt' 21:24:39 INFO - no previously-included directories found matching 'build' 21:24:39 INFO - no previously-included directories found matching 'dist' 21:24:39 INFO - no previously-included directories found matching '.git*' 21:24:39 INFO - Installing collected packages: jsonschema, functools32 21:24:39 INFO - Running setup.py install for functools32 21:24:39 INFO - warning: no files found matching '*.txt' 21:24:39 INFO - no previously-included directories found matching 'build' 21:24:39 INFO - no previously-included directories found matching 'dist' 21:24:39 INFO - no previously-included directories found matching '.git*' 21:24:39 INFO - Successfully installed jsonschema functools32 21:24:39 INFO - Cleaning up... 21:24:39 INFO - Return code: 0 21:24:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:24:39 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')]} 21:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:39 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')]} 21:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:39 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:39 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 21:24:39 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 21:24:39 INFO - Using env: {'DISPLAY': ':0', 21:24:39 INFO - 'HOME': '/home/cltbld', 21:24:39 INFO - 'LANG': 'en_US.UTF-8', 21:24:39 INFO - 'LANGUAGE': 'en_US:en', 21:24:39 INFO - 'LOGNAME': 'cltbld', 21:24:39 INFO - 'MAIL': '/var/mail/cltbld', 21:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:39 INFO - 'MOZ_NO_REMOTE': '1', 21:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:39 INFO - 'NO_EM_RESTART': '1', 21:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:39 INFO - 'PWD': '/builds/slave/test', 21:24:39 INFO - 'SHELL': '/bin/bash', 21:24:39 INFO - 'SHLVL': '1', 21:24:39 INFO - 'TERM': 'linux', 21:24:39 INFO - 'TMOUT': '86400', 21:24:39 INFO - 'USER': 'cltbld', 21:24:39 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:39 INFO - '_': '/tools/buildbot/bin/python'} 21:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:24:39 INFO - Cleaning up... 21:24:39 INFO - Return code: 0 21:24:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:24:39 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')]} 21:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:39 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')]} 21:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:39 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:39 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 21:24:39 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 21:24:39 INFO - Using env: {'DISPLAY': ':0', 21:24:39 INFO - 'HOME': '/home/cltbld', 21:24:39 INFO - 'LANG': 'en_US.UTF-8', 21:24:39 INFO - 'LANGUAGE': 'en_US:en', 21:24:39 INFO - 'LOGNAME': 'cltbld', 21:24:39 INFO - 'MAIL': '/var/mail/cltbld', 21:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:39 INFO - 'MOZ_NO_REMOTE': '1', 21:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:39 INFO - 'NO_EM_RESTART': '1', 21:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:39 INFO - 'PWD': '/builds/slave/test', 21:24:39 INFO - 'SHELL': '/bin/bash', 21:24:39 INFO - 'SHLVL': '1', 21:24:39 INFO - 'TERM': 'linux', 21:24:39 INFO - 'TMOUT': '86400', 21:24:39 INFO - 'USER': 'cltbld', 21:24:39 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:39 INFO - '_': '/tools/buildbot/bin/python'} 21:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:40 INFO - Downloading/unpacking blobuploader==1.2.4 21:24: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 21:24: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 21:24: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 21:24: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 21:24: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 21:24: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 21:24:41 INFO - Downloading blobuploader-1.2.4.tar.gz 21:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:24:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:24:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 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 21:24:42 INFO - Downloading docopt-0.6.1.tar.gz 21:24:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:24:42 INFO - Installing collected packages: blobuploader, requests, docopt 21:24:42 INFO - Running setup.py install for blobuploader 21:24:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:24:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:24:42 INFO - Running setup.py install for requests 21:24:43 INFO - Running setup.py install for docopt 21:24:43 INFO - Successfully installed blobuploader requests docopt 21:24:43 INFO - Cleaning up... 21:24:43 INFO - Return code: 0 21:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:43 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:43 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 21:24:43 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 21:24:43 INFO - Using env: {'DISPLAY': ':0', 21:24:43 INFO - 'HOME': '/home/cltbld', 21:24:43 INFO - 'LANG': 'en_US.UTF-8', 21:24:43 INFO - 'LANGUAGE': 'en_US:en', 21:24:43 INFO - 'LOGNAME': 'cltbld', 21:24:43 INFO - 'MAIL': '/var/mail/cltbld', 21:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:43 INFO - 'MOZ_NO_REMOTE': '1', 21:24:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:43 INFO - 'NO_EM_RESTART': '1', 21:24:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:43 INFO - 'PWD': '/builds/slave/test', 21:24:43 INFO - 'SHELL': '/bin/bash', 21:24:43 INFO - 'SHLVL': '1', 21:24:43 INFO - 'TERM': 'linux', 21:24:43 INFO - 'TMOUT': '86400', 21:24:43 INFO - 'USER': 'cltbld', 21:24:43 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:43 INFO - '_': '/tools/buildbot/bin/python'} 21:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:24:43 INFO - Running setup.py (path:/tmp/pip-fgIaOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:24:43 INFO - Running setup.py (path:/tmp/pip-n_nJIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:24:43 INFO - Running setup.py (path:/tmp/pip-ldh3vZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:24:43 INFO - Running setup.py (path:/tmp/pip-VhedRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:24:43 INFO - Running setup.py (path:/tmp/pip-EBRP0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:24:43 INFO - Running setup.py (path:/tmp/pip-yRiNUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:24:44 INFO - Running setup.py (path:/tmp/pip-W7Nqm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:24:44 INFO - Running setup.py (path:/tmp/pip-RY0AHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:24:44 INFO - Running setup.py (path:/tmp/pip-tHr_md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:24:44 INFO - Running setup.py (path:/tmp/pip-zA5vSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:24:44 INFO - Running setup.py (path:/tmp/pip-gLWG2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:24:44 INFO - Running setup.py (path:/tmp/pip-kNN7VY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:24:44 INFO - Running setup.py (path:/tmp/pip-SioGAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:24:44 INFO - Running setup.py (path:/tmp/pip-hAYRNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:24:44 INFO - Running setup.py (path:/tmp/pip-PaCxGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:24:44 INFO - Running setup.py (path:/tmp/pip-BBW8v3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:24:44 INFO - Running setup.py (path:/tmp/pip-sqlznG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:24:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:24:44 INFO - Running setup.py install for manifestparser 21:24:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:24:44 INFO - Running setup.py install for mozcrash 21:24:45 INFO - Running setup.py install for mozdebug 21:24:45 INFO - Running setup.py install for mozdevice 21:24:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for mozfile 21:24:45 INFO - Running setup.py install for mozhttpd 21:24:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for mozinfo 21:24:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for mozInstall 21:24:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for mozleak 21:24:45 INFO - Running setup.py install for mozlog 21:24:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for moznetwork 21:24:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:24:45 INFO - Running setup.py install for mozprocess 21:24:46 INFO - Running setup.py install for mozprofile 21:24:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:24:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:24:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:24:46 INFO - Running setup.py install for mozrunner 21:24:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:24:46 INFO - Running setup.py install for mozscreenshot 21:24:46 INFO - Running setup.py install for moztest 21:24:46 INFO - Running setup.py install for mozversion 21:24:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:24:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:24:46 INFO - Cleaning up... 21:24:46 INFO - Return code: 0 21:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:46 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:46 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 21:24:46 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 21:24:46 INFO - Using env: {'DISPLAY': ':0', 21:24:46 INFO - 'HOME': '/home/cltbld', 21:24:46 INFO - 'LANG': 'en_US.UTF-8', 21:24:46 INFO - 'LANGUAGE': 'en_US:en', 21:24:46 INFO - 'LOGNAME': 'cltbld', 21:24:46 INFO - 'MAIL': '/var/mail/cltbld', 21:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:46 INFO - 'MOZ_NO_REMOTE': '1', 21:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:46 INFO - 'NO_EM_RESTART': '1', 21:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:46 INFO - 'PWD': '/builds/slave/test', 21:24:46 INFO - 'SHELL': '/bin/bash', 21:24:46 INFO - 'SHLVL': '1', 21:24:46 INFO - 'TERM': 'linux', 21:24:46 INFO - 'TMOUT': '86400', 21:24:46 INFO - 'USER': 'cltbld', 21:24:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:46 INFO - '_': '/tools/buildbot/bin/python'} 21:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:24:46 INFO - Running setup.py (path:/tmp/pip-VH3ToG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:24:46 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)) 21:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:24:46 INFO - Running setup.py (path:/tmp/pip-Wvds5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:24:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:24:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:24:46 INFO - Running setup.py (path:/tmp/pip-XSC4pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:24:47 INFO - Running setup.py (path:/tmp/pip-cwqWfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:24:47 INFO - Running setup.py (path:/tmp/pip-a_gpLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:24:47 INFO - Running setup.py (path:/tmp/pip-1Bc9VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:24:47 INFO - Running setup.py (path:/tmp/pip-6RA2LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:24:47 INFO - Running setup.py (path:/tmp/pip-hNdpIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:24:47 INFO - Running setup.py (path:/tmp/pip-Mkd2qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:24:47 INFO - Running setup.py (path:/tmp/pip-AW7_xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:24:47 INFO - Running setup.py (path:/tmp/pip-dBGytt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:24:47 INFO - Running setup.py (path:/tmp/pip-pxHAcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:24:47 INFO - Running setup.py (path:/tmp/pip-K7srlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:24:47 INFO - Running setup.py (path:/tmp/pip-rK8MKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:24:47 INFO - Running setup.py (path:/tmp/pip-jJNLNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:24:47 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)) 21:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:24:47 INFO - Running setup.py (path:/tmp/pip-iU2jGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:24:48 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)) 21:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:24:48 INFO - Running setup.py (path:/tmp/pip-arNX0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:24:48 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)) 21:24:48 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)) 21:24:48 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)) 21:24:48 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)) 21:24:48 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)) 21:24:48 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)) 21:24:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:24:48 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 21:24:48 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 21:24:48 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 21:24:48 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 21:24:48 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 21:24:48 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 21:24:50 INFO - Downloading blessings-1.6.tar.gz 21:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:24:50 INFO - Installing collected packages: blessings 21:24:50 INFO - Running setup.py install for blessings 21:24:50 INFO - Successfully installed blessings 21:24:50 INFO - Cleaning up... 21:24:50 INFO - Return code: 0 21:24:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:24:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:24:50 INFO - Reading from file tmpfile_stdout 21:24:50 INFO - Current package versions: 21:24:50 INFO - argparse == 1.2.1 21:24:50 INFO - blessings == 1.6 21:24:50 INFO - blobuploader == 1.2.4 21:24:50 INFO - docopt == 0.6.1 21:24:50 INFO - functools32 == 3.2.3-2 21:24:50 INFO - jsonschema == 2.5.1 21:24:50 INFO - manifestparser == 1.1 21:24:50 INFO - mozInstall == 1.12 21:24:50 INFO - mozcrash == 0.17 21:24:50 INFO - mozdebug == 0.1 21:24:50 INFO - mozdevice == 0.48 21:24:50 INFO - mozfile == 1.2 21:24:50 INFO - mozhttpd == 0.7 21:24:50 INFO - mozinfo == 0.9 21:24:50 INFO - mozleak == 0.1 21:24:50 INFO - mozlog == 3.4 21:24:50 INFO - moznetwork == 0.27 21:24:50 INFO - mozprocess == 0.23 21:24:50 INFO - mozprofile == 0.28 21:24:50 INFO - mozrunner == 6.12 21:24:50 INFO - mozscreenshot == 0.1 21:24:50 INFO - mozsystemmonitor == 0.3 21:24:50 INFO - moztest == 0.7 21:24:50 INFO - mozversion == 1.4 21:24:50 INFO - psutil == 3.1.1 21:24:50 INFO - requests == 1.2.3 21:24:50 INFO - wsgiref == 0.1.2 21:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:24:50 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')]} 21:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:50 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')]} 21:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:50 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:50 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 21:24:50 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 21:24:50 INFO - Using env: {'DISPLAY': ':0', 21:24:50 INFO - 'HOME': '/home/cltbld', 21:24:50 INFO - 'LANG': 'en_US.UTF-8', 21:24:50 INFO - 'LANGUAGE': 'en_US:en', 21:24:50 INFO - 'LOGNAME': 'cltbld', 21:24:50 INFO - 'MAIL': '/var/mail/cltbld', 21:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:50 INFO - 'MOZ_NO_REMOTE': '1', 21:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:50 INFO - 'NO_EM_RESTART': '1', 21:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:50 INFO - 'PWD': '/builds/slave/test', 21:24:50 INFO - 'SHELL': '/bin/bash', 21:24:50 INFO - 'SHLVL': '1', 21:24:50 INFO - 'TERM': 'linux', 21:24:50 INFO - 'TMOUT': '86400', 21:24:50 INFO - 'USER': 'cltbld', 21:24:50 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:50 INFO - '_': '/tools/buildbot/bin/python'} 21:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 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)) 21:24:50 INFO - Cleaning up... 21:24:50 INFO - Return code: 0 21:24:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:24:50 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')]} 21:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:50 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')]} 21:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:24:50 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 0x7f02e8a67f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239bca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', '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 21:24:50 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 21:24:50 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 21:24:50 INFO - Using env: {'DISPLAY': ':0', 21:24:50 INFO - 'HOME': '/home/cltbld', 21:24:50 INFO - 'LANG': 'en_US.UTF-8', 21:24:50 INFO - 'LANGUAGE': 'en_US:en', 21:24:50 INFO - 'LOGNAME': 'cltbld', 21:24:50 INFO - 'MAIL': '/var/mail/cltbld', 21:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:50 INFO - 'MOZ_NO_REMOTE': '1', 21:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:50 INFO - 'NO_EM_RESTART': '1', 21:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:50 INFO - 'PWD': '/builds/slave/test', 21:24:50 INFO - 'SHELL': '/bin/bash', 21:24:50 INFO - 'SHLVL': '1', 21:24:50 INFO - 'TERM': 'linux', 21:24:50 INFO - 'TMOUT': '86400', 21:24:50 INFO - 'USER': 'cltbld', 21:24:50 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:50 INFO - '_': '/tools/buildbot/bin/python'} 21:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:24:51 INFO - Cleaning up... 21:24:51 INFO - Return code: 0 21:24:51 INFO - Running post-action listener: _resource_record_post_action 21:24:51 INFO - Running post-action listener: _start_resource_monitoring 21:24:51 INFO - Starting resource monitoring. 21:24:51 INFO - [mozharness: 2016-10-15 04:24:51.220775Z] Finished create-virtualenv step (success) 21:24:51 INFO - [mozharness: 2016-10-15 04:24:51.221338Z] Running install step. 21:24:51 INFO - Running pre-action listener: _resource_record_pre_action 21:24:51 INFO - Running main action method: install 21:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:24:51 INFO - Reading from file tmpfile_stdout 21:24:51 INFO - Detecting whether we're running mozinstall >=1.0... 21:24:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:24:51 INFO - Reading from file tmpfile_stdout 21:24:51 INFO - Output received: 21:24:51 INFO - Usage: mozinstall [options] installer 21:24:51 INFO - Options: 21:24:51 INFO - -h, --help show this help message and exit 21:24:51 INFO - -d DEST, --destination=DEST 21:24:51 INFO - Directory to install application into. [default: 21:24:51 INFO - "/builds/slave/test"] 21:24:51 INFO - --app=APP Application being installed. [default: firefox] 21:24:51 INFO - mkdir: /builds/slave/test/build/application 21:24:51 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'] 21:24:51 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 21:25:08 INFO - Reading from file tmpfile_stdout 21:25:08 INFO - Output received: 21:25:08 INFO - /builds/slave/test/build/application/firefox/firefox 21:25:08 INFO - Running post-action listener: _resource_record_post_action 21:25:08 INFO - [mozharness: 2016-10-15 04:25:08.498583Z] Finished install step (success) 21:25:08 INFO - [mozharness: 2016-10-15 04:25:08.498857Z] Running run-tests step. 21:25:08 INFO - Running pre-action listener: _resource_record_pre_action 21:25:08 INFO - Running main action method: run_tests 21:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:25:08 INFO - Python 2.7.3 21:25:08 INFO - Return code: 0 21:25:08 INFO - grabbing minidump binary from tooltool 21:25:08 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')]} 21:25:08 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 0x2398f60>, '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 0x2399d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239a1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:25:08 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 21:25:08 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 21:25:08 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 21:25:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:25:08 INFO - Return code: 0 21:25:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:25:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:25:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:25:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:25:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:25:08 INFO - Using env: {'DISPLAY': ':0', 21:25:08 INFO - 'HOME': '/home/cltbld', 21:25:08 INFO - 'LANG': 'en_US.UTF-8', 21:25:08 INFO - 'LANGUAGE': 'en_US:en', 21:25:08 INFO - 'LOGNAME': 'cltbld', 21:25:08 INFO - 'MAIL': '/var/mail/cltbld', 21:25:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:25:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:08 INFO - 'MOZ_NO_REMOTE': '1', 21:25:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:08 INFO - 'NO_EM_RESTART': '1', 21:25:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:25:08 INFO - 'PWD': '/builds/slave/test', 21:25:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:25:08 INFO - 'SHELL': '/bin/bash', 21:25:08 INFO - 'SHLVL': '1', 21:25:08 INFO - 'TERM': 'linux', 21:25:08 INFO - 'TMOUT': '86400', 21:25:08 INFO - 'USER': 'cltbld', 21:25:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043', 21:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:08 INFO - '_': '/tools/buildbot/bin/python'} 21:25:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:25:08 INFO - mozversion application_buildid: 20161014183003 21:25:08 INFO - mozversion application_changeset: 3c83a34f913ad840252fba1eda31d656388179c4 21:25:08 INFO - mozversion application_display_name: Nightly 21:25:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:25:08 INFO - mozversion application_name: Firefox 21:25:08 INFO - mozversion application_remotingname: firefox 21:25:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:25:08 INFO - mozversion application_vendor: Mozilla 21:25:08 INFO - mozversion application_version: 52.0a1 21:25:08 INFO - mozversion platform_buildid: 20161014183003 21:25:08 INFO - mozversion platform_changeset: 3c83a34f913ad840252fba1eda31d656388179c4 21:25:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:25:08 INFO - mozversion platform_version: 52.0a1 21:25:08 INFO - using testdate: 1476505508 21:25:08 INFO - actual date: 1476505508 21:25:08 INFO - starting webserver on 'localhost:49355' 21:25:08 INFO - SUITE-START | Running 2 tests 21:25:08 INFO - TEST-START | dromaeo_css 21:25:08 INFO - operating with platform_type : linux_ 21:25:08 INFO - Initialising browser for dromaeo_css test... 21:25:08 INFO - TEST-INFO | started process 2279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU8526h/profile http://localhost:49355/getInfo.html) 21:25:12 INFO - PROCESS | 2279 | __metrics Screen width/height:1600/1200 21:25:12 INFO - PROCESS | 2279 | colorDepth:24 21:25:12 INFO - PROCESS | 2279 | Browser inner width/height: 1024/697 21:25:12 INFO - PROCESS | 2279 | __metrics 21:25:13 INFO - PROCESS | 2279 | 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" 21:25:13 INFO - PROCESS | 2279 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:25:14 INFO - PROCESS | 2279 | console.error: PushService: 21:25:14 INFO - PROCESS | 2279 | stateChangeProcessEnqueue: Error transitioning state 21:25:14 INFO - PROCESS | 2279 | UnknownError 21:25:14 INFO - TEST-INFO | 2279: exit 0 21:25:14 INFO - Browser initialized. 21:25:14 INFO - Running cycle 1/1 for dromaeo_css test... 21:25:14 INFO - TEST-INFO | started process 2431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU8526h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:25:27 INFO - PROCESS | 2431 | RSS: Main: 172261376 21:25:27 INFO - PROCESS | 2431 | 21:26:18 INFO - PROCESS | 2431 | 1476505578490 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] 21:28:28 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49355/tests/dromaeo/cssquery-ext.html) 21:28:30 INFO - PROCESS | 2431 | RSS: Main: 322756608 21:28:30 INFO - PROCESS | 2431 | 21:31:33 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-ext.html (next: http://localhost:49355/tests/dromaeo/cssquery-jquery.html) 21:31:33 INFO - PROCESS | 2431 | RSS: Main: 275349504 21:31:33 INFO - PROCESS | 2431 | 21:34:35 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49355/tests/dromaeo/cssquery-mootools.html) 21:34:36 INFO - PROCESS | 2431 | RSS: Main: 251482112 21:34:36 INFO - PROCESS | 2431 | 21:37:38 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49355/tests/dromaeo/cssquery-prototype.html) 21:37:38 INFO - PROCESS | 2431 | RSS: Main: 270061568 21:37:38 INFO - PROCESS | 2431 | 21:40:41 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49355/tests/dromaeo/cssquery-yui.html) 21:40:42 INFO - PROCESS | 2431 | RSS: Main: 214188032 21:40:42 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | Cycle 1(1): loaded http://localhost:49355/tests/dromaeo/cssquery-yui.html (next: http://localhost:49355/tests/dromaeo/cssquery-dojo.html) 21:43:45 INFO - PROCESS | 2431 | RSS: Main: 303435776 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | __start_tp_report 21:43:45 INFO - PROCESS | 2431 | _x_x_mozilla_page_load 21:43:45 INFO - PROCESS | 2431 | _x_x_mozilla_page_load_details 21:43:45 INFO - PROCESS | 2431 | |i|pagename|runs| 21:43:45 INFO - PROCESS | 2431 | |0;dojo.html;2973.732364087232;3019.6376434827826;3030.1667041563574;3052.7557795376374;3061.540768884667;5163.251328557357;5224.425313215544;5251.842444726654;5317.255584218207;5391.78432862685;5231.73841307935;5255.369355677314;5347.224652425396;5347.358317001966;5351.518363347298;4666.2067448533835;4675.859724208262;4730.455997560273;4734.5265473452555;4754.239321708527;4792.209285467894;4809.350737650425;4849.466558678542;4859.514048595148;4907.803687852499;4656.045510670305;4771.952280477186;4782.0435912817575;4805.159097158001;4882.633802464811;4698.483166851644;4720.433547974265;4763.833265835679;4772.403449568804;4773.236282194832;4708.4820669726305;4709.71741695496;4711.175544279738;4752.857414277577;4782.354382158399;5179.974100129475;5259.105951988171;5274.4198138204765;5278.2082687596785;5281.63028587999;5420.674759514442;5468;5515.448455154453;5561.721913904288;5601.551875849922;5610.24261724666;5630.887382252373;5631.380548139701;5673.6879471629045;5679.545636349124;5239.554637855748;5240.633155679104;5244.763985620656;5247.47525247476;5299.178627312773;5551.555875529948;5602.607817452739;5688.82933511987;5700.600957932905;5710.057840456278;5502.174673798962;5507.449255074541;5514.503694667419;5555.194496797942;5559.332880054339;5546.833594992157;5578.163275508706;5593.216949627055;5594.496495315441;5595.132754423071;5444.074507333763;5508.421615730291;5532.944670553243;5592.664440133605;5650.124230744241;5457.83626491198;5500.037493438712;5501.367342755554;5522.199281104297;5528.778848846082;4242.7242229255;4244.427002354704;4288.785560721952;4289.678274129453;4339.566043395698;3811.6378944000276;3811.904702382463;3842.884713458601;3849.557300910431;3874.1283211277237;5243.606729495303;5317.680939143664;5360.3031605891765;5365.248865158881;5379.273798037215;5633.26767520228;5675.091985282335;5682.289713785777;5688.089905615082;5698.230738850285;5101.234814777738;5353.304070470892;5361.329833770778;5368.382635996911;5424.349078110652;4698.459677137173;4819.518048195223;4853.95146048542;4858;4861.781219845116;5850.94149058504;5858.5898987070495;5859.414058594107;5859.648421094748;5862.501687356623;5629.380768115422;5637.46444087811;5638.520725738358;5639.379668236573;5643.238162847963;5621.55027597783;5680.346760122638;5718.370979192368;5766.106253530709;5782.479576838105;5480.150576660464;5531.806386776443;5536.141898005815;5538.750756215899;5555.138953462219;5592.580556458201;5597.5521958242425;5643.661380317194;5644.153376993484;5645.125005624135;5355.384130824849;5422.430644782241;5455.236266922713;5497.900419915953;5552.639078459887;5510.641808282448;5541.141123125922;5542.418046105101;5547.528460080938;5565.77736890536;5509.779608815603;5518.117101263779;5518.2826232589505;5522.171674248895;5531.751071201725;5372.086745253238;5444.373897001737;5504.669719816824;5556.944430555643;5634.830955071355;5420.512153906167;5443.401225865073;5458;5512.80705175317;5516.3380394352935 21:43:45 INFO - PROCESS | 2431 | |1;ext.html;545.5348738930829;582.7290310005847;600.2137200267647;610.8228613702026;614.9607163893021;31541;42537.361939570896;43228.567714322846;43437;43457.34813977789;43896.78051609737;43917.341239881345;44075.33886991697;44325.55674443254;44353.55646443534;8134.918650813475;8887.911120888773;9039.32205084618;9039.909600904006;9046;8076.676932922676;10565.841512377321;10798.5680572777;10810.72973175673;11099.778004439906;10567.471626418686;10617.203709721764;10620.203484738637;10627.256092073558;10636.627718029871;10820.67537973862;10858.891411085906;10877.619283325046;10885.129189664807;10891.509882055247;10660.147188224923;10667.466626668636;10716.089132423675;10729.302595331279;10745.462726863625;41891.7905410471;42324;42336.36495452571;42349.36475952863;42352;3350.1289664687115;3466.1507930556922;3585.784852908808;3835.347990841564;3847.5382954045695;31190.532142017888;31544.211394715086;31649.36701265962;31991;32041.198970025704;3969.28552860485;4066.247744167309;4078.3066878630702;4236.830526778926;4259.403683484315;316748;317439;317553;318703.9999999977;319046;54741;55418.44581554133;55845.441545584836;55877.44122558804;55946;58248;58566;58584.41415585875;58711;58746.118808217914;16047.197640117947;16360.509184724477;16367;16384.83615163857;16422.260998255108;11412.600558980395;11465.484053217626;11631.302121872715;12671.683207919876;12753.744925101631;15368.848361229437;15842.920785396229;15865.68268634644;15956.2819673115;16010.519684409486;5530.834074977757;5707.3721890592005;5716.371199168088;5864.765409383662;5869.239228231513;842.9241368276762;954.1126752120456;958.9232861371073;1079.542617466414;1118.8643526820283;31180.844095779375;33392.833035834665;33842.15394615154;33933.660663393544;34369.312613747585;1072.2976450424985;1078.6063086973197;1115.7043383503378;1123.887611238886;1134.8241022641503;15571.238641702415;16009.679806403807;16233.2695028724;16407.261673224733;16460.753088703677;474498;476650;477452;477748;479393;54130.18804717933;54333.45666543363;54440.72779636076;54454.72772636112;54576;1195.9581414650445;1236.1347057060093;1291.838520184977;1323.5367621332457;1347.487954577274;27355.726442736115;30999.535006974464;31373.372532549678;32007.839960800047;32065.67934320627;46658.76670616625;47348.52651473441;47422;47429.288560670924;47443;293149;294363;294565;295318;295696;26127.738722612532;26270.322154898713;27074.593881091405;27088.593671095343;28217.43565128727;45712;46272.53727462817;46340.30489542592;46342;47385.52614473811;38885.222295553365;38904.80547597244;38958.4156237651;40850;41564.1687166261;38889.222215556096;39004.41493377545;39656.60343396529;39658.60341396549;40349;38670.6132938667;41233.793831030656;42168.36747448729;42355.576444236394;42537.5746242546;30581;34489.65510344865;35253.64746352606;35824.28351433009;35908.102297442776;26759.331016723958;26843.460273524455;26917.327066823487;27015;27229 21:43:45 INFO - PROCESS | 2431 | |2;jquery.html;366.7689355705905;375.73135208326056;380.7353889047113;389.38282821727563;405.76465649923045;14384.352704128312;15252;18822.24711011558;19067.809321906778;19083.618327633438;17834.375796847115;18230.45308640743;18275.451736447896;18498.260069597232;18619.534511637215;4152.501699796028;5450.127979523277;5694.288213973254;5695.231143795576;5696.544276457874;4241;5910.704464776744;6101.694915254242;6166.4450199482035;6283.246010478749;5453.863653408657;5738.31140263169;6223.1287619733275;6245.40668636481;6389.936100638981;5811.564132690065;5818.7963421280265;5838.9124163137585;5842.328132264802;5848.2689663792025;5546.27898373213;5670.234518340054;5689.573282003867;5715.3998830122655;5719.742611582489;13465.326733663376;13636.386362613606;13649.931750341284;13713.382897769625;13737.862621373759;2134.9573008539896;2163.2753027735725;2177.7060096886876;2184.3992901951933;2221.533477969628;10011.799764004752;10448.947755261252;10449.68650940473;11654.533818647244;14486.275686215648;1855.8515318774466;1945.1441365799144;1953.872998255123;1953.9902300488407;1967.5966426882455;82557.17442825554;86034.56982715109;86344;86506.13493865107;88874.11125888723;10277.794444111076;10575.471226438647;10644.840327395095;10740.892591074145;10796.622118225825;14973.176475293963;14974.700505990037;14974.925125374304;14986.025908316142;14994.70010599782;7699.153093159748;7728.459007869507;7857;7866.331361834308;8156.755297341089;7480.513766605153;7551.811204719926;7655.1962043985695;7661.463697541119;7768.41736869737;4409.470863496401;4524.321351797257;4545.840895568639;4590.977045114753;4656.16189085961;1861.469481197852;1908.293931245448;1933.4575033830467;1937.331620590922;1958.3635318521538;726.46604745512;797.8603744344716;849.2781136034322;915.4598786715869;932.2774849491562;19145.617087658447;19267.325643602406;19282.13230404635;19301.324453644334;19670.32802211828;924.4268553496875;937.386012162035;937.6155776131753;965.5751469353462;1014.6347314966606;10203.591856325813;10680.572777088984;10708.73228169302;10720.624778132884;10739.409332486788;358156;360799;361889;482345;491500;92444;97798.51100744594;98459.61552883562;99895;100612.49693751485;938.2681508423577;971.7910649210454;1011.6307547745167;1046.4872212615765;1051.6266725312405;13593.45626174942;16610.169491525132;17314.134293285035;17370.82629173692;17631.735523967407;30113.096607101823;40781;41788;42500.1499970005;44569.10861782811;51209.48790512047;51276.23085653793;51426;57072.42927570671;59606.44329123706;7700.730474433368;7963.562004089831;7977.162397948132;7999.960000200194;8175.550344731097;10227.539760710935;12047.879521205026;12071.456784444545;12262.570810021607;12638.3680815957;10298.588056477656;11010.944945275221;11138.443077846237;11140.442977851237;11291.153163512934;11139.83290250663;11244.943775281072;11497.252678575866;11514.481848316678;11773.587924422603;10801.567937282422;11434.542618295507;11582.71043223926;11608.709782255173;11741.589044383405;7777.688892444239;7940.682372705027;8238.093809680811;8240.752777416446;8247.134050924806;10394.272400931863;10947.616833410792;10958.616448424267;10976.670699879016;11300.09599232043 21:43:45 INFO - PROCESS | 2431 | |3;mootools.html;1503.4136686692189;1528.9770653440198;1633.2405431474363;1739.7564340992271;1749.9650006999868;8014.879776803353;8293.668253269878;8493.660253589856;9081.137291957262;9105.36262461628;8533.615987280587;8834.514101724402;8862.64549418024;9075.228605568518;9081.90918090819;2190.5728382965326;2207.86752794832;2216.9334919952425;2233.55328934213;2239.664050392446;1978.821906028457;2044.3458093410115;2232.408411770882;2287.9771202288016;2330.2776139396756;2199.9340019799433;2281.064763446987;2281.133169395627;2295.735990361113;2295.919642812502;2211.060299372768;2211.8230541556636;2248.168177774234;2315.548468048731;2325.837191396603;2283.680284760135;2299.1608063057033;2308.8960996755186;2311.295055008222;2346.6597343385283;8848.823023539493;8942.463452192824;8951.597178126935;9023.187913087853;9025.323100767402;5177.301064356301;5321.414644389114;5326.147816349366;5328.973355133199;5336.893262134774;3424.229548351626;3581.194231297963;3676.926461470782;3679.4480827875764;3684.2263124743836;1037.1961729659574;1066.5413872035022;1096.923215374924;1122.1303489795391;1136.5851464215584;12496.125271231018;12529.874701253053;12555.183913045714;12576.874231257754;12587.24476531411;4259.2759230930815;4435.826445041971;4579.335996280587;4605.769711514411;4607.631389488833;10551.736206594896;10562.419066951237;10567.471626418648;10570.577176912837;10581.259311848096;5280.577553795687;5288;5299.894002119936;5338.332708411449;5339.733013349317;4788.61691064714;4843.539863712942;4849.951500485021;4878.975605122023;4885.218365061573;3794.2221844521805;3899.2591407633026;3926.803659816998;3943.7633741975574;3976.58245884178;1129.6215767717742;1177.1759768162322;1224.2776761710634;1251.1804767877054;1251.5306759965013;839.3243439031517;954.1412728544366;966.3380584299778;972.4457059476172;979.603260679426;5955.851103722355;6044.576879618385;6117.785877494266;6160.661163635953;6289.195037002639;936.7377782654812;962.841131213356;1009.2531526670211;1031.5461197073264;1045.2056437107854;4082.3672330788777;4177.791110444527;4178.519470260959;4250.574942505787;4307.978460107679;11092;11339.206255562196;11418;11447.885521144848;11494.137939654393;11339.433028348714;11344;11354.886451135548;11357.261777984404;11372.715682107848;7869.960650196713;7981.680732770624;8061.879071813927;8085.595720213966;8211.17888211174;6051.636901785907;6172.105044768394;6197.597156184834;6203.038529028008;6248.656323902229;10385.84421233667;10392.688219353431;10426.530806113591;10458.738531536772;10588.152947764007;9757;10220.386776793417;10250.077493025663;10550.575672284143;10563;7613.771586852404;7753.689852405841;7759.495632783851;7761.9611901940125;7771.72798952034;8197.79505512367;8263.793405164919;8716.869246961174;8750.606222719865;8767.079456657215;6379.489640828815;7770.766876993699;7784.649690763816;7926.088499822594;8102.149274326347;7746.070471543451;7757.1079325878245;7782.338501227459;7784.41616878725;7821.687132514636;6943.930560694328;7164.355208031302;7206.315400036988;7353.485256032026;7365.668544915598;4912.631552633496;4997.525235102659;5046.646734728533;5137.434882163034;5166.586673066221;4816.301636262792;4855.101806165871;4958.727270000185;5033.295338652519;5070.492950705047 21:43:45 INFO - PROCESS | 2431 | |4;prototype.html;317.69818672261346;327.6903326356593;335.25071465275107;338.04333735528456;341.448560574672;5284;5294.57643388529;5300.7084610346465;5309.469053094688;5388.91916621251;5246.239295302179;5246.7901283948595;5252.973735131329;5280.498352656501;5352.892942141155;4161.313383291761;4170.101779263413;4178.5792830437795;4219.198352313066;4220.366944958266;4115.36211887158;4162;4172.4784401949755;4199.2441360555085;4221.535631080578;4156.854510092147;4164.354525048607;4170.4995400551825;4182.950354326388;4193.769342686151;4135.60711732385;4167.979160104195;4181.749095054306;4185.142045880587;4188.72773269738;3718.8140592970244;3720.5907350191455;3730.3658378075793;3732.664060234592;3740.3454395480953;4948.554630083274;5164.096283150434;5190.377154741417;5207.125434843453;5248.921266181011;4740.795530078495;4743.739094349809;4747.786349614275;4762.452317983441;4765.451973023112;5344.385395679507;5542.750576224039;5558.972205138948;5560.803482624381;5587.636803607753;3941.192055628589;3953.486046813896;3969.0474286171398;3972.7020473464604;4018.136100738326;5452.972735136339;5620.269364982567;5717.599768016241;5738.856528586777;5743.655380677173;3923.2153569286256;3961.969887829185;3970.9205815884097;3971.980140099281;3978.3435733104293;4106.589341065869;4133.875983720493;4134.689898257643;4140.772257525865;4153.54311025787;4648.000679853848;4713.101442612769;4741.099191153669;4757.238841785298;4782.210935195653;5583.659921618863;5589.944100559023;5606.018946684396;5642.548596112383;5690.857728556736;1402.7685431903622;1418.8793952513945;1419.666378401075;1424.8931330150074;1425.7006028733874;871.9520426376483;911.5460839960252;920.5765347939889;936.4662142578801;939.9483028433502;4692.671512994058;4744.715317080917;4765.8570242892765;4791.5687588117235;4798.424189097261;4959.752012399365;4993.675411098267;4996.825111121093;5022.673526220784;5049.798008079709;4572.171131547341;4688.8124475021295;4739.3838800955655;4775.689580177277;4781.131321207248;2645.682518097802;2671.385581316308;2677.8259413138085;2700.3662784015783;2703.337682267857;5182;5240.371155461293;5248;5270.947290527122;5314.654547454403;4778.112775023063;4985.451600323961;5033.496650334937;5082.618803589746;5084.237364395371;5152.660308319864;5179.974100129551;5199.648091496314;5213.2701421800975;5217.217417387346;4967.875803104951;5023.572996295356;5024.949750502448;5046.520580544842;5086.287919691172;4974.756310922269;4978.30303757467;5248.186531087687;5694.686792226302;5697.4332058684195;5677.943220567741;5794.18881356602;5812.331581868139;5887.3523912370465;5927.703614819328;4041.272570937142;4066.9593304067766;4070.348744201032;4107.219628270619;4110.13687125695;5761.654300741969;5780.94219057804;5794.449527294901;5800.303963524404;5802.912956305743;5334.919976200283;5503.449655034465;5583.497485226187;5591.30108736408;5652.349979752381;5600.439956004367;5606.887862242651;5608.214849921096;5611.270534830446;5611.298587676541;5306.787728490817;5565.749541270734;5597.272354593877;5678.74445649955;5700.144978253295;5577.470140336662;5598.460423383602;5642.605017648725;5650.350209725934;5654.236678048411;5525.640833345819;5529.723513824212;5546.838302167887;5554.1391084381985;5586.636868603528 21:43:45 INFO - PROCESS | 2431 | |5;yui.html;787.2835719495258;803.1165717710518;807.442864423548;807.7536351412821;834.3617132893334;15586.532404027883;16607;16734.49796506105;16750.91624541877;16839.91580042101;14324.28378581072;14795.33420996055;14942.178180200084;14949.92525037376;15543.533693989199;791.8376732769797;802.4984384759525;808.7978005498626;808.8018435483315;809.3929552835374;805.7421625079977;811.3874025111033;811.9878201826948;812.0580127052621;812.3744716568239;701.3757755597522;718.4683334332583;827.9130691277419;846.1284876577135;851.7615067781;841.1588411588411;841.1966571923798;842.1494290766309;842.5955541340161;842.6039761312204;606.5481216493696;608.8021393047233;611.150500803883;611.6819253987945;612.1429998002787;11502.07983361329;11833.644990650295;14596.489122880648;15033.699326013528;15115.47095851651;15149.621259468491;15862.52412427629;15893.364265429369;15907.522774316789;16152.111633860133;12309;12397.504099835996;12441;12683.429245683874;13118.081734278605;3754.455603937441;3795.4686343911876;3869.361555343365;3882.669973052294;4028.1742242840146;77131.22868771353;78036.21963780402;78838.60580697117;79036.60481697555;79579.6021019897;11477.598284060017;11493.827592586118;11524.59663911759;11683.415829208505;12259.693507662201;22907.885460572925;22974;22999.42501437444;23026.309210723706;23027.309180724606;8457.746267611858;8529.872051919227;8684.696035638723;8770.605322760492;8862;4502.369668246448;4564.6348292136545;4568.634509239253;4606.838760643362;4658.930116048262;3728.105254738897;3803.1633040730994;3833.712471564588;3853.075261937115;3864.420336949424;632.2192092765431;647.3688154049764;649.0653459019092;652.0870780906769;670.8457054877406;608.4797498139116;636.9904451433232;655.9963256217994;695.3811108113783;720.495653042872;9026.45841249514;9803;9811.901880981099;9909;10183.287169898036;628.3151365012159;681.2941613982894;717.921028686844;733.1715161867056;735.9816004599928;4256.797673056489;4627.9768601156775;4648.721076735406;4671.696339737906;4677.836275730333;119838;120269;120783;122546;126772;83892;101771.49114254382;101771.49114254382;102541;103070.48464757779;80394.59802701067;81026;82004.58997704973;84571.4685650293;87048.5647571758;9293.72118836436;9886.50567471609;9902.752431189278;9943.552540135566;9998;48095.98800149981;48198.759006204746;48257.27614085721;49270;49437.50562494329;66315.33684663284;66496.33503664902;69911.86277989765;71943;73051.37157107232;12744.74510509803;12867.935660322013;12868.356582171042;13126.60620181396;13199.934000329937;14635.560933172022;14771.630709232355;14878.925605371904;14991.775123373376;15244.77132842986;13920.234387108401;14116.717665646835;14375.424983000563;14417.279136042946;14657.52945351678;13649.590512284647;13709;13889.72220555563;14424.423023079378;14696.485623003144;12503.37483125822;12845;13029;13054.282014489294;13324.200547967153;10956.397398143179;11464.828027579426;11655;11703.531858725555;11745.647630571097;11352.886471135183;11513.30920144794;11803.409829508662;11848;11911.761764764482 21:43:45 INFO - PROCESS | 2431 | __end_tp_report 21:43:45 INFO - PROCESS | 2431 | __start_cc_report 21:43:45 INFO - PROCESS | 2431 | _x_x_mozilla_cycle_collect,1436 21:43:45 INFO - PROCESS | 2431 | __end_cc_report 21:43:45 INFO - PROCESS | 2431 | __startTimestamp1476506625717__endTimestamp 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | ------- Summary: start ------- 21:43:45 INFO - PROCESS | 2431 | Number of tests: 6 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#0] dojo.html Cycles:165 Average:5212.57 Median:5444.37 stddev:591.44 (10.9%) stddev-sans-first:566.58 21:43:45 INFO - PROCESS | 2431 | Values: 2973.7 3019.6 3030.2 3052.8 3061.5 5163.3 5224.4 5251.8 5317.3 5391.8 5231.7 5255.4 5347.2 5347.4 5351.5 4666.2 4675.9 4730.5 4734.5 4754.2 4792.2 4809.4 4849.5 4859.5 4907.8 4656.0 4772.0 4782.0 4805.2 4882.6 4698.5 4720.4 4763.8 4772.4 4773.2 4708.5 4709.7 4711.2 4752.9 4782.4 5180.0 5259.1 5274.4 5278.2 5281.6 5420.7 5468.0 5515.4 5561.7 5601.6 5610.2 5630.9 5631.4 5673.7 5679.5 5239.6 5240.6 5244.8 5247.5 5299.2 5551.6 5602.6 5688.8 5700.6 5710.1 5502.2 5507.4 5514.5 5555.2 5559.3 5546.8 5578.2 5593.2 5594.5 5595.1 5444.1 5508.4 5532.9 5592.7 5650.1 5457.8 5500.0 5501.4 5522.2 5528.8 4242.7 4244.4 4288.8 4289.7 4339.6 3811.6 3811.9 3842.9 3849.6 3874.1 5243.6 5317.7 5360.3 5365.2 5379.3 5633.3 5675.1 5682.3 5688.1 5698.2 5101.2 5353.3 5361.3 5368.4 5424.3 4698.5 4819.5 4854.0 4858.0 4861.8 5850.9 5858.6 5859.4 5859.6 5862.5 5629.4 5637.5 5638.5 5639.4 5643.2 5621.6 5680.3 5718.4 5766.1 5782.5 5480.2 5531.8 5536.1 5538.8 5555.1 5592.6 5597.6 5643.7 5644.2 5645.1 5355.4 5422.4 5455.2 5497.9 5552.6 5510.6 5541.1 5542.4 5547.5 5565.8 5509.8 5518.1 5518.3 5522.2 5531.8 5372.1 5444.4 5504.7 5556.9 5634.8 5420.5 5443.4 5458.0 5512.8 5516.3 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#1] ext.html Cycles:180 Average:53135.65 Median:19359.90 stddev:98205.57 (507.3%) stddev-sans-first:98401.68 21:43:45 INFO - PROCESS | 2431 | Values: 545.5 582.7 600.2 610.8 615.0 31541.0 42537.4 43228.6 43437.0 43457.3 43896.8 43917.3 44075.3 44325.6 44353.6 8134.9 8887.9 9039.3 9039.9 9046.0 8076.7 10565.8 10798.6 10810.7 11099.8 10567.5 10617.2 10620.2 10627.3 10636.6 10820.7 10858.9 10877.6 10885.1 10891.5 10660.1 10667.5 10716.1 10729.3 10745.5 41891.8 42324.0 42336.4 42349.4 42352.0 3350.1 3466.2 3585.8 3835.3 3847.5 31190.5 31544.2 31649.4 31991.0 32041.2 3969.3 4066.2 4078.3 4236.8 4259.4 316748.0 317439.0 317553.0 318704.0 319046.0 54741.0 55418.4 55845.4 55877.4 55946.0 58248.0 58566.0 58584.4 58711.0 58746.1 16047.2 16360.5 16367.0 16384.8 16422.3 11412.6 11465.5 11631.3 12671.7 12753.7 15368.8 15842.9 15865.7 15956.3 16010.5 5530.8 5707.4 5716.4 5864.8 5869.2 842.9 954.1 958.9 1079.5 1118.9 31180.8 33392.8 33842.2 33933.7 34369.3 1072.3 1078.6 1115.7 1123.9 1134.8 15571.2 16009.7 16233.3 16407.3 16460.8 474498.0 476650.0 477452.0 477748.0 479393.0 54130.2 54333.5 54440.7 54454.7 54576.0 1196.0 1236.1 1291.8 1323.5 1347.5 27355.7 30999.5 31373.4 32007.8 32065.7 46658.8 47348.5 47422.0 47429.3 47443.0 293149.0 294363.0 294565.0 295318.0 295696.0 26127.7 26270.3 27074.6 27088.6 28217.4 45712.0 46272.5 46340.3 46342.0 47385.5 38885.2 38904.8 38958.4 40850.0 41564.2 38889.2 39004.4 39656.6 39658.6 40349.0 38670.6 41233.8 42168.4 42355.6 42537.6 30581.0 34489.7 35253.6 35824.3 35908.1 26759.3 26843.5 26917.3 27015.0 27229.0 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#2] jquery.html Cycles:180 Average:26447.53 Median:194134.55 stddev:69364.83 (35.7%) stddev-sans-first:69531.78 21:43:45 INFO - PROCESS | 2431 | Values: 366.8 375.7 380.7 389.4 405.8 14384.4 15252.0 18822.2 19067.8 19083.6 17834.4 18230.5 18275.5 18498.3 18619.5 4152.5 5450.1 5694.3 5695.2 5696.5 4241.0 5910.7 6101.7 6166.4 6283.2 5453.9 5738.3 6223.1 6245.4 6389.9 5811.6 5818.8 5838.9 5842.3 5848.3 5546.3 5670.2 5689.6 5715.4 5719.7 13465.3 13636.4 13649.9 13713.4 13737.9 2135.0 2163.3 2177.7 2184.4 2221.5 10011.8 10448.9 10449.7 11654.5 14486.3 1855.9 1945.1 1953.9 1954.0 1967.6 82557.2 86034.6 86344.0 86506.1 88874.1 10277.8 10575.5 10644.8 10740.9 10796.6 14973.2 14974.7 14974.9 14986.0 14994.7 7699.2 7728.5 7857.0 7866.3 8156.8 7480.5 7551.8 7655.2 7661.5 7768.4 4409.5 4524.3 4545.8 4591.0 4656.2 1861.5 1908.3 1933.5 1937.3 1958.4 726.5 797.9 849.3 915.5 932.3 19145.6 19267.3 19282.1 19301.3 19670.3 924.4 937.4 937.6 965.6 1014.6 10203.6 10680.6 10708.7 10720.6 10739.4 358156.0 360799.0 361889.0 482345.0 491500.0 92444.0 97798.5 98459.6 99895.0 100612.5 938.3 971.8 1011.6 1046.5 1051.6 13593.5 16610.2 17314.1 17370.8 17631.7 30113.1 40781.0 41788.0 42500.1 44569.1 51209.5 51276.2 51426.0 57072.4 59606.4 7700.7 7963.6 7977.2 8000.0 8175.6 10227.5 12047.9 12071.5 12262.6 12638.4 10298.6 11010.9 11138.4 11140.4 11291.2 11139.8 11244.9 11497.3 11514.5 11773.6 10801.6 11434.5 11582.7 11608.7 11741.6 7777.7 7940.7 8238.1 8240.8 8247.1 10394.3 10947.6 10958.6 10976.7 11300.1 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#3] mootools.html Cycles:180 Average:5859.28 Median:4606.70 stddev:3388.53 (73.6%) stddev-sans-first:3382.23 21:43:45 INFO - PROCESS | 2431 | Values: 1503.4 1529.0 1633.2 1739.8 1750.0 8014.9 8293.7 8493.7 9081.1 9105.4 8533.6 8834.5 8862.6 9075.2 9081.9 2190.6 2207.9 2216.9 2233.6 2239.7 1978.8 2044.3 2232.4 2288.0 2330.3 2199.9 2281.1 2281.1 2295.7 2295.9 2211.1 2211.8 2248.2 2315.5 2325.8 2283.7 2299.2 2308.9 2311.3 2346.7 8848.8 8942.5 8951.6 9023.2 9025.3 5177.3 5321.4 5326.1 5329.0 5336.9 3424.2 3581.2 3676.9 3679.4 3684.2 1037.2 1066.5 1096.9 1122.1 1136.6 12496.1 12529.9 12555.2 12576.9 12587.2 4259.3 4435.8 4579.3 4605.8 4607.6 10551.7 10562.4 10567.5 10570.6 10581.3 5280.6 5288.0 5299.9 5338.3 5339.7 4788.6 4843.5 4850.0 4879.0 4885.2 3794.2 3899.3 3926.8 3943.8 3976.6 1129.6 1177.2 1224.3 1251.2 1251.5 839.3 954.1 966.3 972.4 979.6 5955.9 6044.6 6117.8 6160.7 6289.2 936.7 962.8 1009.3 1031.5 1045.2 4082.4 4177.8 4178.5 4250.6 4308.0 11092.0 11339.2 11418.0 11447.9 11494.1 11339.4 11344.0 11354.9 11357.3 11372.7 7870.0 7981.7 8061.9 8085.6 8211.2 6051.6 6172.1 6197.6 6203.0 6248.7 10385.8 10392.7 10426.5 10458.7 10588.2 9757.0 10220.4 10250.1 10550.6 10563.0 7613.8 7753.7 7759.5 7762.0 7771.7 8197.8 8263.8 8716.9 8750.6 8767.1 6379.5 7770.8 7784.6 7926.1 8102.1 7746.1 7757.1 7782.3 7784.4 7821.7 6943.9 7164.4 7206.3 7353.5 7365.7 4912.6 4997.5 5046.6 5137.4 5166.6 4816.3 4855.1 4958.7 5033.3 5070.5 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#4] prototype.html Cycles:180 Average:4547.24 Median:5024.26 stddev:1319.18 (26.3%) stddev-sans-first:1284.11 21:43:45 INFO - PROCESS | 2431 | Values: 317.7 327.7 335.3 338.0 341.4 5284.0 5294.6 5300.7 5309.5 5388.9 5246.2 5246.8 5253.0 5280.5 5352.9 4161.3 4170.1 4178.6 4219.2 4220.4 4115.4 4162.0 4172.5 4199.2 4221.5 4156.9 4164.4 4170.5 4183.0 4193.8 4135.6 4168.0 4181.7 4185.1 4188.7 3718.8 3720.6 3730.4 3732.7 3740.3 4948.6 5164.1 5190.4 5207.1 5248.9 4740.8 4743.7 4747.8 4762.5 4765.5 5344.4 5542.8 5559.0 5560.8 5587.6 3941.2 3953.5 3969.0 3972.7 4018.1 5453.0 5620.3 5717.6 5738.9 5743.7 3923.2 3962.0 3970.9 3972.0 3978.3 4106.6 4133.9 4134.7 4140.8 4153.5 4648.0 4713.1 4741.1 4757.2 4782.2 5583.7 5589.9 5606.0 5642.5 5690.9 1402.8 1418.9 1419.7 1424.9 1425.7 872.0 911.5 920.6 936.5 939.9 4692.7 4744.7 4765.9 4791.6 4798.4 4959.8 4993.7 4996.8 5022.7 5049.8 4572.2 4688.8 4739.4 4775.7 4781.1 2645.7 2671.4 2677.8 2700.4 2703.3 5182.0 5240.4 5248.0 5270.9 5314.7 4778.1 4985.5 5033.5 5082.6 5084.2 5152.7 5180.0 5199.6 5213.3 5217.2 4967.9 5023.6 5024.9 5046.5 5086.3 4974.8 4978.3 5248.2 5694.7 5697.4 5677.9 5794.2 5812.3 5887.4 5927.7 4041.3 4067.0 4070.3 4107.2 4110.1 5761.7 5780.9 5794.4 5800.3 5802.9 5334.9 5503.4 5583.5 5591.3 5652.3 5600.4 5606.9 5608.2 5611.3 5611.3 5306.8 5565.7 5597.3 5678.7 5700.1 5577.5 5598.5 5642.6 5650.4 5654.2 5525.6 5529.7 5546.8 5554.1 5586.6 21:43:45 INFO - PROCESS | 2431 | 21:43:45 INFO - PROCESS | 2431 | [#5] yui.html Cycles:180 Average:20904.93 Median:4379.58 stddev:30153.16 (688.5%) stddev-sans-first:30199.91 21:43:45 INFO - PROCESS | 2431 | Values: 787.3 803.1 807.4 807.8 834.4 15586.5 16607.0 16734.5 16750.9 16839.9 14324.3 14795.3 14942.2 14949.9 15543.5 791.8 802.5 808.8 808.8 809.4 805.7 811.4 812.0 812.1 812.4 701.4 718.5 827.9 846.1 851.8 841.2 841.2 842.1 842.6 842.6 606.5 608.8 611.2 611.7 612.1 11502.1 11833.6 14596.5 15033.7 15115.5 15149.6 15862.5 15893.4 15907.5 16152.1 12309.0 12397.5 12441.0 12683.4 13118.1 3754.5 3795.5 3869.4 3882.7 4028.2 77131.2 78036.2 78838.6 79036.6 79579.6 11477.6 11493.8 11524.6 11683.4 12259.7 22907.9 22974.0 22999.4 23026.3 23027.3 8457.7 8529.9 8684.7 8770.6 8862.0 4502.4 4564.6 4568.6 4606.8 4658.9 3728.1 3803.2 3833.7 3853.1 3864.4 632.2 647.4 649.1 652.1 670.8 608.5 637.0 656.0 695.4 720.5 9026.5 9803.0 9811.9 9909.0 10183.3 628.3 681.3 717.9 733.2 736.0 4256.8 4628.0 4648.7 4671.7 4677.8 119838.0 120269.0 120783.0 122546.0 126772.0 83892.0 101771.5 101771.5 102541.0 103070.5 80394.6 81026.0 82004.6 84571.5 87048.6 9293.7 9886.5 9902.8 9943.6 9998.0 48096.0 48198.8 48257.3 49270.0 49437.5 66315.3 66496.3 69911.9 71943.0 73051.4 12744.7 12867.9 12868.4 13126.6 13199.9 14635.6 14771.6 14878.9 14991.8 15244.8 13920.2 14116.7 14375.4 14417.3 14657.5 13649.6 13709.0 13889.7 14424.4 14696.5 12503.4 12845.0 13029.0 13054.3 13324.2 10956.4 11464.8 11655.0 11703.5 11745.6 11352.9 11513.3 11803.4 11848.0 11911.8 21:43:45 INFO - PROCESS | 2431 | -------- Summary: end -------- 21:43:45 INFO - PROCESS | 2431 | 21:43:46 INFO - TEST-INFO | 2431: exit 0 21:43:46 INFO - TEST-OK | dromaeo_css | took 1117908ms 21:43:46 INFO - TEST-START | kraken 21:43:46 INFO - operating with platform_type : linux_ 21:43:46 INFO - Initialising browser for kraken test... 21:43:46 INFO - TEST-INFO | started process 2848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Qk68h/profile http://localhost:49355/getInfo.html) 21:43:50 INFO - PROCESS | 2848 | __metrics Screen width/height:1600/1200 21:43:50 INFO - PROCESS | 2848 | colorDepth:24 21:43:50 INFO - PROCESS | 2848 | Browser inner width/height: 1024/697 21:43:50 INFO - PROCESS | 2848 | __metrics 21:43:51 INFO - PROCESS | 2848 | 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" 21:43:51 INFO - PROCESS | 2848 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:43:51 INFO - PROCESS | 2848 | console.error: PushService: 21:43:51 INFO - PROCESS | 2848 | stateChangeProcessEnqueue: Error transitioning state 21:43:51 INFO - PROCESS | 2848 | UnknownError 21:43:52 INFO - TEST-INFO | 2848: exit 0 21:43:52 INFO - Browser initialized. 21:43:52 INFO - Running cycle 1/1 for kraken test... 21:43:52 INFO - TEST-INFO | started process 2950 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6Qk68h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:44:04 INFO - PROCESS | 2950 | RSS: Main: 171454464 21:44:04 INFO - PROCESS | 2950 | 21:44:07 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-beat-detection) 21:44:08 INFO - PROCESS | 2950 | RSS: Main: 228622336 21:44:08 INFO - PROCESS | 2950 | 21:44:10 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-dft) 21:44:11 INFO - PROCESS | 2950 | RSS: Main: 215793664 21:44:11 INFO - PROCESS | 2950 | 21:44:14 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-fft) 21:44:15 INFO - PROCESS | 2950 | RSS: Main: 297488384 21:44:15 INFO - PROCESS | 2950 | 21:44:17 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49355/tests/kraken/driver.html?testName=audio-oscillator) 21:44:17 INFO - PROCESS | 2950 | RSS: Main: 206131200 21:44:17 INFO - PROCESS | 2950 | 21:44:19 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:44:20 INFO - PROCESS | 2950 | RSS: Main: 211603456 21:44:20 INFO - PROCESS | 2950 | 21:44:23 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-darkroom) 21:44:24 INFO - PROCESS | 2950 | RSS: Main: 247910400 21:44:24 INFO - PROCESS | 2950 | 21:44:27 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49355/tests/kraken/driver.html?testName=imaging-desaturate) 21:44:28 INFO - PROCESS | 2950 | RSS: Main: 246272000 21:44:28 INFO - PROCESS | 2950 | 21:44:30 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49355/tests/kraken/driver.html?testName=json-parse-financial) 21:44:31 INFO - PROCESS | 2950 | RSS: Main: 246349824 21:44:31 INFO - PROCESS | 2950 | 21:44:32 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49355/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:44:33 INFO - PROCESS | 2950 | RSS: Main: 208924672 21:44:33 INFO - PROCESS | 2950 | 21:44:35 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:44:35 INFO - PROCESS | 2950 | RSS: Main: 216354816 21:44:35 INFO - PROCESS | 2950 | 21:44:37 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:44:38 INFO - PROCESS | 2950 | RSS: Main: 226074624 21:44:38 INFO - PROCESS | 2950 | 21:44:41 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:44:41 INFO - PROCESS | 2950 | RSS: Main: 229322752 21:44:41 INFO - PROCESS | 2950 | 21:44:44 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:44:45 INFO - PROCESS | 2950 | RSS: Main: 231211008 21:44:45 INFO - PROCESS | 2950 | 21:44:46 INFO - PROCESS | 2950 | Cycle 1(1): loaded http://localhost:49355/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49355/tests/kraken/driver.html?testName=ai-astar) 21:44:47 INFO - PROCESS | 2950 | RSS: Main: 281841664 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | __start_tp_report 21:44:47 INFO - PROCESS | 2950 | _x_x_mozilla_page_load 21:44:47 INFO - PROCESS | 2950 | _x_x_mozilla_page_load_details 21:44:47 INFO - PROCESS | 2950 | |i|pagename|runs| 21:44:47 INFO - PROCESS | 2950 | |0;ai-astar;103;110;110;109;113;110;111;110;110;110 21:44:47 INFO - PROCESS | 2950 | |1;audio-beat-detection;151;122;125;150;127;122;121;124;157;127 21:44:47 INFO - PROCESS | 2950 | |2;audio-dft;160;160;160;159;160;160;160;162;161;162 21:44:47 INFO - PROCESS | 2950 | |3;audio-fft;75;75;75;120;76;77;77;77;105;74 21:44:47 INFO - PROCESS | 2950 | |4;audio-oscillator;83;83;113;84;85;84;110;83;82;110 21:44:47 INFO - PROCESS | 2950 | |5;imaging-gaussian-blur;116;118;117;117;117;118;117;117;117;117 21:44:47 INFO - PROCESS | 2950 | |6;imaging-darkroom;146;146;145;146;146;146;145;145;145;145 21:44:47 INFO - PROCESS | 2950 | |7;imaging-desaturate;84;84;85;85;85;85;85;85;85;85 21:44:47 INFO - PROCESS | 2950 | |8;json-parse-financial;52;50;50;51;50;52;52;52;51;53 21:44:47 INFO - PROCESS | 2950 | |9;json-stringify-tinderbox;60;60;61;61;62;60;61;59;64;60 21:44:47 INFO - PROCESS | 2950 | |10;stanford-crypto-aes;89;85;81;81;79;83;80;79;78;79 21:44:47 INFO - PROCESS | 2950 | |11;stanford-crypto-ccm;134;137;142;136;130;134;135;142;186;131 21:44:47 INFO - PROCESS | 2950 | |12;stanford-crypto-pbkdf2;170;165;168;167;170;164;163;168;167;166 21:44:47 INFO - PROCESS | 2950 | |13;stanford-crypto-sha256-iterative;68;66;65;64;66;64;65;65;66;64 21:44:47 INFO - PROCESS | 2950 | __end_tp_report 21:44:47 INFO - PROCESS | 2950 | __start_cc_report 21:44:47 INFO - PROCESS | 2950 | _x_x_mozilla_cycle_collect,1689 21:44:47 INFO - PROCESS | 2950 | __end_cc_report 21:44:47 INFO - PROCESS | 2950 | __startTimestamp1476506687065__endTimestamp 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | ------- Summary: start ------- 21:44:47 INFO - PROCESS | 2950 | Number of tests: 14 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.55 (2.3%) stddev-sans-first:1.12 21:44:47 INFO - PROCESS | 2950 | Values: 103.0 110.0 110.0 109.0 113.0 110.0 111.0 110.0 110.0 110.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#1] audio-beat-detection Cycles:10 Average:132.60 Median:127.00 stddev:14.10 (11.1%) stddev-sans-first:13.30 21:44:47 INFO - PROCESS | 2950 | Values: 151.0 122.0 125.0 150.0 127.0 122.0 121.0 124.0 157.0 127.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 21:44:47 INFO - PROCESS | 2950 | Values: 160.0 160.0 160.0 159.0 160.0 160.0 160.0 162.0 161.0 162.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#3] audio-fft Cycles:10 Average:83.10 Median:75.50 stddev:15.93 (21.1%) stddev-sans-first:16.62 21:44:47 INFO - PROCESS | 2950 | Values: 75.0 75.0 75.0 120.0 76.0 77.0 77.0 77.0 105.0 74.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:83.00 stddev:13.37 (16.1%) stddev-sans-first:13.80 21:44:47 INFO - PROCESS | 2950 | Values: 83.0 83.0 113.0 84.0 85.0 84.0 110.0 83.0 82.0 110.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 21:44:47 INFO - PROCESS | 2950 | Values: 116.0 118.0 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 21:44:47 INFO - PROCESS | 2950 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:0.42 (0.5%) stddev-sans-first:0.33 21:44:47 INFO - PROCESS | 2950 | Values: 84.0 84.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 85.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:1.09 21:44:47 INFO - PROCESS | 2950 | Values: 52.0 50.0 50.0 51.0 50.0 52.0 52.0 52.0 51.0 53.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:1.40 (2.3%) stddev-sans-first:1.45 21:44:47 INFO - PROCESS | 2950 | Values: 60.0 60.0 61.0 61.0 62.0 60.0 61.0 59.0 64.0 60.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:3.41 (4.2%) stddev-sans-first:2.24 21:44:47 INFO - PROCESS | 2950 | Values: 89.0 85.0 81.0 81.0 79.0 83.0 80.0 79.0 78.0 79.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:136.50 stddev:16.40 (12.0%) stddev-sans-first:17.22 21:44:47 INFO - PROCESS | 2950 | Values: 134.0 137.0 142.0 136.0 130.0 134.0 135.0 142.0 186.0 131.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:167.50 stddev:2.35 (1.4%) stddev-sans-first:2.19 21:44:47 INFO - PROCESS | 2950 | Values: 170.0 165.0 168.0 167.0 170.0 164.0 163.0 168.0 167.0 166.0 21:44:47 INFO - PROCESS | 2950 | 21:44:47 INFO - PROCESS | 2950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.25 (1.9%) stddev-sans-first:0.87 21:44:47 INFO - PROCESS | 2950 | Values: 68.0 66.0 65.0 64.0 66.0 64.0 65.0 65.0 66.0 64.0 21:44:47 INFO - PROCESS | 2950 | -------- Summary: end -------- 21:44:47 INFO - PROCESS | 2950 | 21:44:48 INFO - TEST-INFO | 2950: exit 0 21:44:48 INFO - TEST-OK | kraken | took 61590ms 21:44:48 INFO - SUITE-END | took 1179s 21:44:48 INFO - Completed test suite (00:19:39) 21:44:48 INFO - javascript benchmark 21:44:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2973.732364087232, 3019.6376434827826, 3030.1667041563574, 3052.7557795376374, 3061.540768884667, 5163.251328557357, 5224.425313215544, 5251.842444726654, 5317.255584218207, 5391.78432862685, 5231.73841307935, 5255.369355677314, 5347.224652425396, 5347.358317001966, 5351.518363347298, 4666.2067448533835, 4675.859724208262, 4730.455997560273, 4734.5265473452555, 4754.239321708527, 4792.209285467894, 4809.350737650425, 4849.466558678542, 4859.514048595148, 4907.803687852499, 4656.045510670305, 4771.952280477186, 4782.0435912817575, 4805.159097158001, 4882.633802464811, 4698.483166851644, 4720.433547974265, 4763.833265835679, 4772.403449568804, 4773.236282194832, 4708.4820669726305, 4709.71741695496, 4711.175544279738, 4752.857414277577, 4782.354382158399, 5179.974100129475, 5259.105951988171, 5274.4198138204765, 5278.2082687596785, 5281.63028587999, 5420.674759514442, 5468.0, 5515.448455154453, 5561.721913904288, 5601.551875849922, 5610.24261724666, 5630.887382252373, 5631.380548139701, 5673.6879471629045, 5679.545636349124, 5239.554637855748, 5240.633155679104, 5244.763985620656, 5247.47525247476, 5299.178627312773, 5551.555875529948, 5602.607817452739, 5688.82933511987, 5700.600957932905, 5710.057840456278, 5502.174673798962, 5507.449255074541, 5514.503694667419, 5555.194496797942, 5559.332880054339, 5546.833594992157, 5578.163275508706, 5593.216949627055, 5594.496495315441, 5595.132754423071, 5444.074507333763, 5508.421615730291, 5532.944670553243, 5592.664440133605, 5650.124230744241, 5457.83626491198, 5500.037493438712, 5501.367342755554, 5522.199281104297, 5528.778848846082, 4242.7242229255, 4244.427002354704, 4288.785560721952, 4289.678274129453, 4339.566043395698, 3811.6378944000276, 3811.904702382463, 3842.884713458601, 3849.557300910431, 3874.1283211277237, 5243.606729495303, 5317.680939143664, 5360.3031605891765, 5365.248865158881, 5379.273798037215, 5633.26767520228, 5675.091985282335, 5682.289713785777, 5688.089905615082, 5698.230738850285, 5101.234814777738, 5353.304070470892, 5361.329833770778, 5368.382635996911, 5424.349078110652, 4698.459677137173, 4819.518048195223, 4853.95146048542, 4858.0, 4861.781219845116, 5850.94149058504, 5858.5898987070495, 5859.414058594107, 5859.648421094748, 5862.501687356623, 5629.380768115422, 5637.46444087811, 5638.520725738358, 5639.379668236573, 5643.238162847963, 5621.55027597783, 5680.346760122638, 5718.370979192368, 5766.106253530709, 5782.479576838105, 5480.150576660464, 5531.806386776443, 5536.141898005815, 5538.750756215899, 5555.138953462219, 5592.580556458201, 5597.5521958242425, 5643.661380317194, 5644.153376993484, 5645.125005624135, 5355.384130824849, 5422.430644782241, 5455.236266922713, 5497.900419915953, 5552.639078459887, 5510.641808282448, 5541.141123125922, 5542.418046105101, 5547.528460080938, 5565.77736890536, 5509.779608815603, 5518.117101263779, 5518.2826232589505, 5522.171674248895, 5531.751071201725, 5372.086745253238, 5444.373897001737, 5504.669719816824, 5556.944430555643, 5634.830955071355, 5420.512153906167, 5443.401225865073, 5458.0, 5512.80705175317, 5516.3380394352935], "value": 5172.504745130592, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.5348738930829, 582.7290310005847, 600.2137200267647, 610.8228613702026, 614.9607163893021, 31541.0, 42537.361939570896, 43228.567714322846, 43437.0, 43457.34813977789, 43896.78051609737, 43917.341239881345, 44075.33886991697, 44325.55674443254, 44353.55646443534, 8134.918650813475, 8887.911120888773, 9039.32205084618, 9039.909600904006, 9046.0, 8076.676932922676, 10565.841512377321, 10798.5680572777, 10810.72973175673, 11099.778004439906, 10567.471626418686, 10617.203709721764, 10620.203484738637, 10627.256092073558, 10636.627718029871, 10820.67537973862, 10858.891411085906, 10877.619283325046, 10885.129189664807, 10891.509882055247, 10660.147188224923, 10667.466626668636, 10716.089132423675, 10729.302595331279, 10745.462726863625, 41891.7905410471, 42324.0, 42336.36495452571, 42349.36475952863, 42352.0, 3350.1289664687115, 3466.1507930556922, 3585.784852908808, 3835.347990841564, 3847.5382954045695, 31190.532142017888, 31544.211394715086, 31649.36701265962, 31991.0, 32041.198970025704, 3969.28552860485, 4066.247744167309, 4078.3066878630702, 4236.830526778926, 4259.403683484315, 316748.0, 317439.0, 317553.0, 318703.9999999977, 319046.0, 54741.0, 55418.44581554133, 55845.441545584836, 55877.44122558804, 55946.0, 58248.0, 58566.0, 58584.41415585875, 58711.0, 58746.118808217914, 16047.197640117947, 16360.509184724477, 16367.0, 16384.83615163857, 16422.260998255108, 11412.600558980395, 11465.484053217626, 11631.302121872715, 12671.683207919876, 12753.744925101631, 15368.848361229437, 15842.920785396229, 15865.68268634644, 15956.2819673115, 16010.519684409486, 5530.834074977757, 5707.3721890592005, 5716.371199168088, 5864.765409383662, 5869.239228231513, 842.9241368276762, 954.1126752120456, 958.9232861371073, 1079.542617466414, 1118.8643526820283, 31180.844095779375, 33392.833035834665, 33842.15394615154, 33933.660663393544, 34369.312613747585, 1072.2976450424985, 1078.6063086973197, 1115.7043383503378, 1123.887611238886, 1134.8241022641503, 15571.238641702415, 16009.679806403807, 16233.2695028724, 16407.261673224733, 16460.753088703677, 474498.0, 476650.0, 477452.0, 477748.0, 479393.0, 54130.18804717933, 54333.45666543363, 54440.72779636076, 54454.72772636112, 54576.0, 1195.9581414650445, 1236.1347057060093, 1291.838520184977, 1323.5367621332457, 1347.487954577274, 27355.726442736115, 30999.535006974464, 31373.372532549678, 32007.839960800047, 32065.67934320627, 46658.76670616625, 47348.52651473441, 47422.0, 47429.288560670924, 47443.0, 293149.0, 294363.0, 294565.0, 295318.0, 295696.0, 26127.738722612532, 26270.322154898713, 27074.593881091405, 27088.593671095343, 28217.43565128727, 45712.0, 46272.53727462817, 46340.30489542592, 46342.0, 47385.52614473811, 38885.222295553365, 38904.80547597244, 38958.4156237651, 40850.0, 41564.1687166261, 38889.222215556096, 39004.41493377545, 39656.60343396529, 39658.60341396549, 40349.0, 38670.6132938667, 41233.793831030656, 42168.36747448729, 42355.576444236394, 42537.5746242546, 30581.0, 34489.65510344865, 35253.64746352606, 35824.28351433009, 35908.102297442776, 26759.331016723958, 26843.460273524455, 26917.327066823487, 27015.0, 27229.0], "value": 19502.717947538804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.7689355705905, 375.73135208326056, 380.7353889047113, 389.38282821727563, 405.76465649923045, 14384.352704128312, 15252.0, 18822.24711011558, 19067.809321906778, 19083.618327633438, 17834.375796847115, 18230.45308640743, 18275.451736447896, 18498.260069597232, 18619.534511637215, 4152.501699796028, 5450.127979523277, 5694.288213973254, 5695.231143795576, 5696.544276457874, 4241.0, 5910.704464776744, 6101.694915254242, 6166.4450199482035, 6283.246010478749, 5453.863653408657, 5738.31140263169, 6223.1287619733275, 6245.40668636481, 6389.936100638981, 5811.564132690065, 5818.7963421280265, 5838.9124163137585, 5842.328132264802, 5848.2689663792025, 5546.27898373213, 5670.234518340054, 5689.573282003867, 5715.3998830122655, 5719.742611582489, 13465.326733663376, 13636.386362613606, 13649.931750341284, 13713.382897769625, 13737.862621373759, 2134.9573008539896, 2163.2753027735725, 2177.7060096886876, 2184.3992901951933, 2221.533477969628, 10011.799764004752, 10448.947755261252, 10449.68650940473, 11654.533818647244, 14486.275686215648, 1855.8515318774466, 1945.1441365799144, 1953.872998255123, 1953.9902300488407, 1967.5966426882455, 82557.17442825554, 86034.56982715109, 86344.0, 86506.13493865107, 88874.11125888723, 10277.794444111076, 10575.471226438647, 10644.840327395095, 10740.892591074145, 10796.622118225825, 14973.176475293963, 14974.700505990037, 14974.925125374304, 14986.025908316142, 14994.70010599782, 7699.153093159748, 7728.459007869507, 7857.0, 7866.331361834308, 8156.755297341089, 7480.513766605153, 7551.811204719926, 7655.1962043985695, 7661.463697541119, 7768.41736869737, 4409.470863496401, 4524.321351797257, 4545.840895568639, 4590.977045114753, 4656.16189085961, 1861.469481197852, 1908.293931245448, 1933.4575033830467, 1937.331620590922, 1958.3635318521538, 726.46604745512, 797.8603744344716, 849.2781136034322, 915.4598786715869, 932.2774849491562, 19145.617087658447, 19267.325643602406, 19282.13230404635, 19301.324453644334, 19670.32802211828, 924.4268553496875, 937.386012162035, 937.6155776131753, 965.5751469353462, 1014.6347314966606, 10203.591856325813, 10680.572777088984, 10708.73228169302, 10720.624778132884, 10739.409332486788, 358156.0, 360799.0, 361889.0, 482345.0, 491500.0, 92444.0, 97798.51100744594, 98459.61552883562, 99895.0, 100612.49693751485, 938.2681508423577, 971.7910649210454, 1011.6307547745167, 1046.4872212615765, 1051.6266725312405, 13593.45626174942, 16610.169491525132, 17314.134293285035, 17370.82629173692, 17631.735523967407, 30113.096607101823, 40781.0, 41788.0, 42500.1499970005, 44569.10861782811, 51209.48790512047, 51276.23085653793, 51426.0, 57072.42927570671, 59606.44329123706, 7700.730474433368, 7963.562004089831, 7977.162397948132, 7999.960000200194, 8175.550344731097, 10227.539760710935, 12047.879521205026, 12071.456784444545, 12262.570810021607, 12638.3680815957, 10298.588056477656, 11010.944945275221, 11138.443077846237, 11140.442977851237, 11291.153163512934, 11139.83290250663, 11244.943775281072, 11497.252678575866, 11514.481848316678, 11773.587924422603, 10801.567937282422, 11434.542618295507, 11582.71043223926, 11608.709782255173, 11741.589044383405, 7777.688892444239, 7940.682372705027, 8238.093809680811, 8240.752777416446, 8247.134050924806, 10394.272400931863, 10947.616833410792, 10958.616448424267, 10976.670699879016, 11300.09599232043], "value": 8798.043718168145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1503.4136686692189, 1528.9770653440198, 1633.2405431474363, 1739.7564340992271, 1749.9650006999868, 8014.879776803353, 8293.668253269878, 8493.660253589856, 9081.137291957262, 9105.36262461628, 8533.615987280587, 8834.514101724402, 8862.64549418024, 9075.228605568518, 9081.90918090819, 2190.5728382965326, 2207.86752794832, 2216.9334919952425, 2233.55328934213, 2239.664050392446, 1978.821906028457, 2044.3458093410115, 2232.408411770882, 2287.9771202288016, 2330.2776139396756, 2199.9340019799433, 2281.064763446987, 2281.133169395627, 2295.735990361113, 2295.919642812502, 2211.060299372768, 2211.8230541556636, 2248.168177774234, 2315.548468048731, 2325.837191396603, 2283.680284760135, 2299.1608063057033, 2308.8960996755186, 2311.295055008222, 2346.6597343385283, 8848.823023539493, 8942.463452192824, 8951.597178126935, 9023.187913087853, 9025.323100767402, 5177.301064356301, 5321.414644389114, 5326.147816349366, 5328.973355133199, 5336.893262134774, 3424.229548351626, 3581.194231297963, 3676.926461470782, 3679.4480827875764, 3684.2263124743836, 1037.1961729659574, 1066.5413872035022, 1096.923215374924, 1122.1303489795391, 1136.5851464215584, 12496.125271231018, 12529.874701253053, 12555.183913045714, 12576.874231257754, 12587.24476531411, 4259.2759230930815, 4435.826445041971, 4579.335996280587, 4605.769711514411, 4607.631389488833, 10551.736206594896, 10562.419066951237, 10567.471626418648, 10570.577176912837, 10581.259311848096, 5280.577553795687, 5288.0, 5299.894002119936, 5338.332708411449, 5339.733013349317, 4788.61691064714, 4843.539863712942, 4849.951500485021, 4878.975605122023, 4885.218365061573, 3794.2221844521805, 3899.2591407633026, 3926.803659816998, 3943.7633741975574, 3976.58245884178, 1129.6215767717742, 1177.1759768162322, 1224.2776761710634, 1251.1804767877054, 1251.5306759965013, 839.3243439031517, 954.1412728544366, 966.3380584299778, 972.4457059476172, 979.603260679426, 5955.851103722355, 6044.576879618385, 6117.785877494266, 6160.661163635953, 6289.195037002639, 936.7377782654812, 962.841131213356, 1009.2531526670211, 1031.5461197073264, 1045.2056437107854, 4082.3672330788777, 4177.791110444527, 4178.519470260959, 4250.574942505787, 4307.978460107679, 11092.0, 11339.206255562196, 11418.0, 11447.885521144848, 11494.137939654393, 11339.433028348714, 11344.0, 11354.886451135548, 11357.261777984404, 11372.715682107848, 7869.960650196713, 7981.680732770624, 8061.879071813927, 8085.595720213966, 8211.17888211174, 6051.636901785907, 6172.105044768394, 6197.597156184834, 6203.038529028008, 6248.656323902229, 10385.84421233667, 10392.688219353431, 10426.530806113591, 10458.738531536772, 10588.152947764007, 9757.0, 10220.386776793417, 10250.077493025663, 10550.575672284143, 10563.0, 7613.771586852404, 7753.689852405841, 7759.495632783851, 7761.9611901940125, 7771.72798952034, 8197.79505512367, 8263.793405164919, 8716.869246961174, 8750.606222719865, 8767.079456657215, 6379.489640828815, 7770.766876993699, 7784.649690763816, 7926.088499822594, 8102.149274326347, 7746.070471543451, 7757.1079325878245, 7782.338501227459, 7784.41616878725, 7821.687132514636, 6943.930560694328, 7164.355208031302, 7206.315400036988, 7353.485256032026, 7365.668544915598, 4912.631552633496, 4997.525235102659, 5046.646734728533, 5137.434882163034, 5166.586673066221, 4816.301636262792, 4855.101806165871, 4958.727270000185, 5033.295338652519, 5070.492950705047], "value": 4657.017349167665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.69818672261346, 327.6903326356593, 335.25071465275107, 338.04333735528456, 341.448560574672, 5284.0, 5294.57643388529, 5300.7084610346465, 5309.469053094688, 5388.91916621251, 5246.239295302179, 5246.7901283948595, 5252.973735131329, 5280.498352656501, 5352.892942141155, 4161.313383291761, 4170.101779263413, 4178.5792830437795, 4219.198352313066, 4220.366944958266, 4115.36211887158, 4162.0, 4172.4784401949755, 4199.2441360555085, 4221.535631080578, 4156.854510092147, 4164.354525048607, 4170.4995400551825, 4182.950354326388, 4193.769342686151, 4135.60711732385, 4167.979160104195, 4181.749095054306, 4185.142045880587, 4188.72773269738, 3718.8140592970244, 3720.5907350191455, 3730.3658378075793, 3732.664060234592, 3740.3454395480953, 4948.554630083274, 5164.096283150434, 5190.377154741417, 5207.125434843453, 5248.921266181011, 4740.795530078495, 4743.739094349809, 4747.786349614275, 4762.452317983441, 4765.451973023112, 5344.385395679507, 5542.750576224039, 5558.972205138948, 5560.803482624381, 5587.636803607753, 3941.192055628589, 3953.486046813896, 3969.0474286171398, 3972.7020473464604, 4018.136100738326, 5452.972735136339, 5620.269364982567, 5717.599768016241, 5738.856528586777, 5743.655380677173, 3923.2153569286256, 3961.969887829185, 3970.9205815884097, 3971.980140099281, 3978.3435733104293, 4106.589341065869, 4133.875983720493, 4134.689898257643, 4140.772257525865, 4153.54311025787, 4648.000679853848, 4713.101442612769, 4741.099191153669, 4757.238841785298, 4782.210935195653, 5583.659921618863, 5589.944100559023, 5606.018946684396, 5642.548596112383, 5690.857728556736, 1402.7685431903622, 1418.8793952513945, 1419.666378401075, 1424.8931330150074, 1425.7006028733874, 871.9520426376483, 911.5460839960252, 920.5765347939889, 936.4662142578801, 939.9483028433502, 4692.671512994058, 4744.715317080917, 4765.8570242892765, 4791.5687588117235, 4798.424189097261, 4959.752012399365, 4993.675411098267, 4996.825111121093, 5022.673526220784, 5049.798008079709, 4572.171131547341, 4688.8124475021295, 4739.3838800955655, 4775.689580177277, 4781.131321207248, 2645.682518097802, 2671.385581316308, 2677.8259413138085, 2700.3662784015783, 2703.337682267857, 5182.0, 5240.371155461293, 5248.0, 5270.947290527122, 5314.654547454403, 4778.112775023063, 4985.451600323961, 5033.496650334937, 5082.618803589746, 5084.237364395371, 5152.660308319864, 5179.974100129551, 5199.648091496314, 5213.2701421800975, 5217.217417387346, 4967.875803104951, 5023.572996295356, 5024.949750502448, 5046.520580544842, 5086.287919691172, 4974.756310922269, 4978.30303757467, 5248.186531087687, 5694.686792226302, 5697.4332058684195, 5677.943220567741, 5794.18881356602, 5812.331581868139, 5887.3523912370465, 5927.703614819328, 4041.272570937142, 4066.9593304067766, 4070.348744201032, 4107.219628270619, 4110.13687125695, 5761.654300741969, 5780.94219057804, 5794.449527294901, 5800.303963524404, 5802.912956305743, 5334.919976200283, 5503.449655034465, 5583.497485226187, 5591.30108736408, 5652.349979752381, 5600.439956004367, 5606.887862242651, 5608.214849921096, 5611.270534830446, 5611.298587676541, 5306.787728490817, 5565.749541270734, 5597.272354593877, 5678.74445649955, 5700.144978253295, 5577.470140336662, 5598.460423383602, 5642.605017648725, 5650.350209725934, 5654.236678048411, 5525.640833345819, 5529.723513824212, 5546.838302167887, 5554.1391084381985, 5586.636868603528], "value": 4128.724024118607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.2835719495258, 803.1165717710518, 807.442864423548, 807.7536351412821, 834.3617132893334, 15586.532404027883, 16607.0, 16734.49796506105, 16750.91624541877, 16839.91580042101, 14324.28378581072, 14795.33420996055, 14942.178180200084, 14949.92525037376, 15543.533693989199, 791.8376732769797, 802.4984384759525, 808.7978005498626, 808.8018435483315, 809.3929552835374, 805.7421625079977, 811.3874025111033, 811.9878201826948, 812.0580127052621, 812.3744716568239, 701.3757755597522, 718.4683334332583, 827.9130691277419, 846.1284876577135, 851.7615067781, 841.1588411588411, 841.1966571923798, 842.1494290766309, 842.5955541340161, 842.6039761312204, 606.5481216493696, 608.8021393047233, 611.150500803883, 611.6819253987945, 612.1429998002787, 11502.07983361329, 11833.644990650295, 14596.489122880648, 15033.699326013528, 15115.47095851651, 15149.621259468491, 15862.52412427629, 15893.364265429369, 15907.522774316789, 16152.111633860133, 12309.0, 12397.504099835996, 12441.0, 12683.429245683874, 13118.081734278605, 3754.455603937441, 3795.4686343911876, 3869.361555343365, 3882.669973052294, 4028.1742242840146, 77131.22868771353, 78036.21963780402, 78838.60580697117, 79036.60481697555, 79579.6021019897, 11477.598284060017, 11493.827592586118, 11524.59663911759, 11683.415829208505, 12259.693507662201, 22907.885460572925, 22974.0, 22999.42501437444, 23026.309210723706, 23027.309180724606, 8457.746267611858, 8529.872051919227, 8684.696035638723, 8770.605322760492, 8862.0, 4502.369668246448, 4564.6348292136545, 4568.634509239253, 4606.838760643362, 4658.930116048262, 3728.105254738897, 3803.1633040730994, 3833.712471564588, 3853.075261937115, 3864.420336949424, 632.2192092765431, 647.3688154049764, 649.0653459019092, 652.0870780906769, 670.8457054877406, 608.4797498139116, 636.9904451433232, 655.9963256217994, 695.3811108113783, 720.495653042872, 9026.45841249514, 9803.0, 9811.901880981099, 9909.0, 10183.287169898036, 628.3151365012159, 681.2941613982894, 717.921028686844, 733.1715161867056, 735.9816004599928, 4256.797673056489, 4627.9768601156775, 4648.721076735406, 4671.696339737906, 4677.836275730333, 119838.0, 120269.0, 120783.0, 122546.0, 126772.0, 83892.0, 101771.49114254382, 101771.49114254382, 102541.0, 103070.48464757779, 80394.59802701067, 81026.0, 82004.58997704973, 84571.4685650293, 87048.5647571758, 9293.72118836436, 9886.50567471609, 9902.752431189278, 9943.552540135566, 9998.0, 48095.98800149981, 48198.759006204746, 48257.27614085721, 49270.0, 49437.50562494329, 66315.33684663284, 66496.33503664902, 69911.86277989765, 71943.0, 73051.37157107232, 12744.74510509803, 12867.935660322013, 12868.356582171042, 13126.60620181396, 13199.934000329937, 14635.560933172022, 14771.630709232355, 14878.925605371904, 14991.775123373376, 15244.77132842986, 13920.234387108401, 14116.717665646835, 14375.424983000563, 14417.279136042946, 14657.52945351678, 13649.590512284647, 13709.0, 13889.72220555563, 14424.423023079378, 14696.485623003144, 12503.37483125822, 12845.0, 13029.0, 13054.282014489294, 13324.200547967153, 10956.397398143179, 11464.828027579426, 11655.0, 11703.531858725555, 11745.647630571097, 11352.886471135183, 11513.30920144794, 11803.409829508662, 11848.0, 11911.761764764482], "value": 7609.546357308032, "unit": "score"}], "name": "dromaeo_css", "value": 7116.256489930665, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 110.0, 109.0, 113.0, 110.0, 111.0, 110.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 122.0, 125.0, 150.0, 127.0, 122.0, 121.0, 124.0, 157.0, 127.0], "value": 132.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 159.0, 160.0, 160.0, 160.0, 162.0, 161.0, 162.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 120.0, 76.0, 77.0, 77.0, 77.0, 105.0, 74.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 113.0, 84.0, 85.0, 84.0, 110.0, 83.0, 82.0, 110.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 51.0, 50.0, 52.0, 52.0, 52.0, 51.0, 53.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 61.0, 62.0, 60.0, 61.0, 59.0, 64.0, 60.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 81.0, 81.0, 79.0, 83.0, 80.0, 79.0, 78.0, 79.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 142.0, 136.0, 130.0, 134.0, 135.0, 142.0, 186.0, 131.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 168.0, 167.0, 170.0, 164.0, 163.0, 168.0, 167.0, 166.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 65.0, 64.0, 66.0, 64.0, 65.0, 65.0, 66.0, 64.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1491.1000000000001, "alertThreshold": 2.0}]} 21:44:48 INFO - Return code: 0 21:44:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:44:48 INFO - # TBPL SUCCESS # 21:44:48 INFO - Running post-action listener: _resource_record_post_action 21:44:48 INFO - [mozharness: 2016-10-15 04:44:48.880822Z] Finished run-tests step (success) 21:44:48 INFO - Running post-run listener: _resource_record_post_run 21:44:48 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392773632; Read time: 12; Write time: 292272 21:44:48 INFO - TinderboxPrint: CPU usage
13.1% 21:44:48 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 21:44:48 INFO - TinderboxPrint: I/O write bytes / time
392,773,632 / 292,272 21:44:48 INFO - TinderboxPrint: CPU idle
8,297.3 (86.9%) 21:44:48 INFO - TinderboxPrint: CPU user
1,203.3 (12.6%) 21:44:48 INFO - TinderboxPrint: Swap in / out
0 / 0 21:44:48 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103247872; Read time: 0; Write time: 90644 21:44:48 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 288944128; Read time: 0; Write time: 201588 21:44:49 INFO - Running post-run listener: _upload_blobber_files 21:44:49 INFO - Blob upload gear active. 21:44:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:44:49 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 21:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:44:49 INFO - (blobuploader) - INFO - Open directory for files ... 21:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:44:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:44:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:44:53 INFO - (blobuploader) - INFO - Done attempting. 21:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:44:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:44:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:44:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:44:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:44:53 INFO - (blobuploader) - INFO - Done attempting. 21:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:44:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:44:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:44:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:44:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:44:54 INFO - (blobuploader) - INFO - Done attempting. 21:44:54 INFO - (blobuploader) - INFO - Iteration through files over. 21:44:54 INFO - Return code: 0 21:44:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:44:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:44:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/14625dab9df9a59cfa2878666e44abe1ac4c2e811be6c6e640d48b5919a6a95561503359dc48c7bb280eb966d58d226631ae22ebd47847092e3accca7fa9007c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ffb84b1921ce74b3e3b2832ac686f0687f409a53fe1dd8c5d5a2a30c60884ea360c75d4ddafc00dcb7808d1ef7ca3b5a3ad17cc319fdd069deaf889b39fd586c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a26592c8fe9b527c4cafc554e9fd71140fb482d4c16b73443c4b72409a31054fcb5fbbe2207f5fce512fdc7995cda88d258917122616b4aaaf0ab314ca4c336f"} 21:44:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:44:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:44:54 INFO - Contents: 21:44:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/14625dab9df9a59cfa2878666e44abe1ac4c2e811be6c6e640d48b5919a6a95561503359dc48c7bb280eb966d58d226631ae22ebd47847092e3accca7fa9007c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ffb84b1921ce74b3e3b2832ac686f0687f409a53fe1dd8c5d5a2a30c60884ea360c75d4ddafc00dcb7808d1ef7ca3b5a3ad17cc319fdd069deaf889b39fd586c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a26592c8fe9b527c4cafc554e9fd71140fb482d4c16b73443c4b72409a31054fcb5fbbe2207f5fce512fdc7995cda88d258917122616b4aaaf0ab314ca4c336f"} 21:44:54 INFO - Running post-run listener: copy_logs_to_upload_dir 21:44:54 INFO - Copying logs to upload dir... 21:44:54 INFO - mkdir: /builds/slave/test/build/upload/logs 21:44:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.004474 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-10-14 21:44:54.591029) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 21:44:54.597610) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/14625dab9df9a59cfa2878666e44abe1ac4c2e811be6c6e640d48b5919a6a95561503359dc48c7bb280eb966d58d226631ae22ebd47847092e3accca7fa9007c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ffb84b1921ce74b3e3b2832ac686f0687f409a53fe1dd8c5d5a2a30c60884ea360c75d4ddafc00dcb7808d1ef7ca3b5a3ad17cc319fdd069deaf889b39fd586c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a26592c8fe9b527c4cafc554e9fd71140fb482d4c16b73443c4b72409a31054fcb5fbbe2207f5fce512fdc7995cda88d258917122616b4aaaf0ab314ca4c336f"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1476495003/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/14625dab9df9a59cfa2878666e44abe1ac4c2e811be6c6e640d48b5919a6a95561503359dc48c7bb280eb966d58d226631ae22ebd47847092e3accca7fa9007c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ffb84b1921ce74b3e3b2832ac686f0687f409a53fe1dd8c5d5a2a30c60884ea360c75d4ddafc00dcb7808d1ef7ca3b5a3ad17cc319fdd069deaf889b39fd586c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a26592c8fe9b527c4cafc554e9fd71140fb482d4c16b73443c4b72409a31054fcb5fbbe2207f5fce512fdc7995cda88d258917122616b4aaaf0ab314ca4c336f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 21:44:54.626760) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:44:54.627075) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1476498756.427190-915594043 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 21:44:54.705512) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 21:44:54.705823) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 21:44:54.706111) ========= ========= Total master_lag: 0.19 =========