builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1481249246.47 results: success (0) buildid: 20161208172753 builduid: f653be16ed6644e683e8826f73d57e5f revision: 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.468244) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.468693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.468964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.483645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.483965) ========= 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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-08 18:07:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.5M=0s 2016-12-08 18:07:26 (42.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090323 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.587951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.588257) ========= 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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018992 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:26.637830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 18:07:26.638305) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 --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 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 --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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-08 18:07:26,839 truncating revision to first 12 chars 2016-12-08 18:07:26,839 Setting DEBUG logging. 2016-12-08 18:07:26,839 attempt 1/10 2016-12-08 18:07:26,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/28d3c72eaecd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-08 18:07:28,004 unpacking tar archive at: mozilla-inbound-28d3c72eaecd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.508509 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-08 18:07:28.160950) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:28.161264) ========= script_repo_revision: 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:28.161653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:28.161915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-08 18:07:28.171151) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 18:07:28.171416) ========= /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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:07:28 INFO - MultiFileLogger online at 20161208 18:07:28 in /builds/slave/test 18:07:28 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 18:07:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:07:28 INFO - {'append_to_log': False, 18:07:28 INFO - 'base_work_dir': '/builds/slave/test', 18:07:28 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 18:07:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:07:28 INFO - 'branch': 'Mozilla-Inbound', 18:07:28 INFO - 'buildbot_json_path': 'buildprops.json', 18:07:28 INFO - 'config_files': ('talos/linux_config.py',), 18:07:28 INFO - 'default_actions': ('clobber', 18:07:28 INFO - 'read-buildbot-config', 18:07:28 INFO - 'download-and-extract', 18:07:28 INFO - 'populate-webroot', 18:07:28 INFO - 'create-virtualenv', 18:07:28 INFO - 'install', 18:07:28 INFO - 'run-tests'), 18:07:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:07:28 INFO - 'download_minidump_stackwalk': True, 18:07:28 INFO - 'download_symbols': 'ondemand', 18:07:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:07:28 INFO - 'tooltool.py': '/tools/tooltool.py', 18:07:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:07:28 INFO - '/tools/misc-python/virtualenv.py')}, 18:07:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:07:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:07:28 INFO - 'installer_path': 'installer.exe', 18:07:28 INFO - 'log_level': 'info', 18:07:28 INFO - 'log_name': 'talos', 18:07:28 INFO - 'log_to_console': True, 18:07:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:07:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:07:28 INFO - 'opt_config_files': (), 18:07:28 INFO - 'pip_index': False, 18:07:28 INFO - 'sps_profile': False, 18:07:28 INFO - 'sps_profile_interval': 0, 18:07:28 INFO - 'suite': 'dromaeojs', 18:07:28 INFO - 'system_bits': '32', 18:07:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:07:28 INFO - 'title': 'talos-linux64-ix-016', 18:07:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:07:28 INFO - 'use_talos_json': True, 18:07:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:07:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:07:28 INFO - 'work_dir': 'build'} 18:07:28 INFO - [mozharness: 2016-12-09 02:07:28.474224Z] Running clobber step. 18:07:28 INFO - Running pre-action listener: _resource_record_pre_action 18:07:28 INFO - Running main action method: clobber 18:07:28 INFO - rmtree: /builds/slave/test/build 18:07:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:07:29 INFO - Running post-action listener: _resource_record_post_action 18:07:29 INFO - [mozharness: 2016-12-09 02:07:29.498787Z] Finished clobber step (success) 18:07:29 INFO - [mozharness: 2016-12-09 02:07:29.498885Z] Running read-buildbot-config step. 18:07:29 INFO - Running pre-action listener: _resource_record_pre_action 18:07:29 INFO - Running main action method: read_buildbot_config 18:07:29 INFO - Using buildbot properties: 18:07:29 INFO - { 18:07:29 INFO - "project": "", 18:07:29 INFO - "product": "firefox", 18:07:29 INFO - "script_repo_revision": "production", 18:07:29 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 18:07:29 INFO - "repository": "", 18:07:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 18:07:29 INFO - "buildid": "20161208172753", 18:07:29 INFO - "stage_platform": "linux64-pgo", 18:07:29 INFO - "basedir": "/builds/slave/test", 18:07:29 INFO - "buildnumber": 2211, 18:07:29 INFO - "platform": "ubuntu64_hw", 18:07:29 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:07:29 INFO - "slavebuilddir": "test", 18:07:29 INFO - "branch": "mozilla-inbound", 18:07:29 INFO - "revision": "28d3c72eaecdc719fa4a01d5bb2d345b216070a9", 18:07:29 INFO - "slavename": "talos-linux64-ix-016", 18:07:29 INFO - "builduid": "f653be16ed6644e683e8826f73d57e5f", 18:07:29 INFO - "repo_path": "integration/mozilla-inbound" 18:07:29 INFO - } 18:07:29 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:07:29 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 18:07:29 INFO - Running post-action listener: _resource_record_post_action 18:07:29 INFO - [mozharness: 2016-12-09 02:07:29.501231Z] Finished read-buildbot-config step (success) 18:07:29 INFO - [mozharness: 2016-12-09 02:07:29.501317Z] Running download-and-extract step. 18:07:29 INFO - Running pre-action listener: _resource_record_pre_action 18:07:29 INFO - Running main action method: download_and_extract 18:07:29 INFO - mkdir: /builds/slave/test/build/tests 18:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:29 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:07:29 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:07:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:07:29 INFO - Downloaded 1361 bytes. 18:07:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:07:29 INFO - Using the following test package requirements: 18:07:29 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:07:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:07:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:07:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'jsshell-linux-x86_64.zip'], 18:07:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:07:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:07:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:07:29 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:07:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:07:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:07:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:07:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:07:29 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:07:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:07:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:07:29 INFO - Expected file size: 21784893 18:07:29 INFO - Obtained file size: 21784893 18:07:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:07:33 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:07:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:07:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:07:34 INFO - Expected file size: 21784893 18:07:34 INFO - Obtained file size: 21784893 18:07:38 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:07:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:07:38 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:07:38 INFO - Expected file size: 14409734 18:07:38 INFO - Obtained file size: 14409734 18:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:38 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:07:38 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:07:44 WARNING - URL Error: https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:07:44 INFO - Running command: ['nslookup', 'archive.mozilla.org'] 18:07:44 INFO - Copy/paste: nslookup archive.mozilla.org 18:07:44 INFO - Server: 10.26.75.40 18:07:44 INFO - Address: 10.26.75.40#53 18:07:44 INFO - archive.mozilla.org canonical name = d34chcsvb7ug62.cloudfront.net. 18:07:44 INFO - Name: d34chcsvb7ug62.cloudfront.net 18:07:44 INFO - Address: 54.230.118.40 18:07:44 INFO - Return code: 0 18:07:44 INFO - retry: attempt #1 caught exception: 18:07:44 INFO - retry: Failed, sleeping 30 seconds before retrying 18:08:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #2 18:08:16 INFO - Downloaded 59839388 bytes. 18:08:16 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:08:16 INFO - mkdir: /builds/slave/test/properties 18:08:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:16 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:16 INFO - Contents: 18:08:16 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:08:16 INFO - Running post-action listener: _resource_record_post_action 18:08:16 INFO - Running post-action listener: set_extra_try_arguments 18:08:16 INFO - [mozharness: 2016-12-09 02:08:16.588071Z] Finished download-and-extract step (success) 18:08:16 INFO - [mozharness: 2016-12-09 02:08:16.588307Z] Running populate-webroot step. 18:08:16 INFO - Running pre-action listener: _resource_record_pre_action 18:08:16 INFO - Running main action method: populate_webroot 18:08:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:08:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:08:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:08:16 INFO - '--tppagecycles', 18:08:16 INFO - '7'], 18:08:16 INFO - 'tests': ['tsvgm']}}, 18:08:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:08:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:08:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['damp', 'tps']}, 18:08:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'tests': ['damp', 'tps']}, 18:08:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['dromaeo_dom']}, 18:08:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['basic_compositor_video']}, 18:08:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:08:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['a11yr', 18:08:16 INFO - 'ts_paint', 18:08:16 INFO - 'tpaint', 18:08:16 INFO - 'sessionrestore', 18:08:16 INFO - 'sessionrestore_no_auto_restore', 18:08:16 INFO - 'tabpaint']}, 18:08:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['tsvgx', 18:08:16 INFO - 'tsvgr_opacity', 18:08:16 INFO - 'tart', 18:08:16 INFO - 'tscrollx', 18:08:16 INFO - 'cart', 18:08:16 INFO - 'tsvg_static']}, 18:08:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:16 INFO - 'tsvgr_opacity', 18:08:16 INFO - 'tart', 18:08:16 INFO - 'tscrollx', 18:08:16 INFO - 'cart', 18:08:16 INFO - 'tsvg_static']}, 18:08:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'talos_options': ['--disable-e10s'], 18:08:16 INFO - 'tests': ['tp5o']}, 18:08:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:08:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'talos_options': ['--disable-e10s', 18:08:16 INFO - '--xperf_path', 18:08:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:16 INFO - 'tests': ['tp5n']}, 18:08:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:08:16 INFO - 'talos_options': ['--xperf_path', 18:08:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:16 INFO - 'tests': ['tp5n']}}, 18:08:16 INFO - 'talos.zip': {'path': '', 18:08:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:08:16 INFO - Running post-action listener: _resource_record_post_action 18:08:16 INFO - [mozharness: 2016-12-09 02:08:16.603358Z] Finished populate-webroot step (success) 18:08:16 INFO - [mozharness: 2016-12-09 02:08:16.603446Z] Running create-virtualenv step. 18:08:16 INFO - Running pre-action listener: _resource_record_pre_action 18:08:16 INFO - Running main action method: create_virtualenv 18:08:16 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:16 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 18:08:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:08:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:16 INFO - Using real prefix '/usr' 18:08:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:17 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:19 INFO - Installing pip.................done. 18:08:19 INFO - Return code: 0 18:08:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:08:19 INFO - Reading from file tmpfile_stdout 18:08:19 INFO - Output received: 18:08:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:08:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:08:19 INFO - Using env: {'DISPLAY': ':0', 18:08:19 INFO - 'HOME': '/home/cltbld', 18:08:19 INFO - 'LANG': 'en_US.UTF-8', 18:08:19 INFO - 'LANGUAGE': 'en_US:en', 18:08:19 INFO - 'LOGNAME': 'cltbld', 18:08:19 INFO - 'MAIL': '/var/mail/cltbld', 18:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:19 INFO - 'MOZ_NO_REMOTE': '1', 18:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:19 INFO - 'NO_EM_RESTART': '1', 18:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:19 INFO - 'PWD': '/builds/slave/test', 18:08:19 INFO - 'SHELL': '/bin/bash', 18:08:19 INFO - 'SHLVL': '1', 18:08:19 INFO - 'TERM': 'linux', 18:08:19 INFO - 'TMOUT': '86400', 18:08:19 INFO - 'USER': 'cltbld', 18:08:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:19 INFO - '_': '/tools/buildbot/bin/python'} 18:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:08:19 INFO - Cleaning up... 18:08:19 INFO - Return code: 0 18:08:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:19 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:08:19 INFO - Using env: {'DISPLAY': ':0', 18:08:19 INFO - 'HOME': '/home/cltbld', 18:08:19 INFO - 'LANG': 'en_US.UTF-8', 18:08:19 INFO - 'LANGUAGE': 'en_US:en', 18:08:19 INFO - 'LOGNAME': 'cltbld', 18:08:19 INFO - 'MAIL': '/var/mail/cltbld', 18:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:19 INFO - 'MOZ_NO_REMOTE': '1', 18:08:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:19 INFO - 'NO_EM_RESTART': '1', 18:08:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:19 INFO - 'PWD': '/builds/slave/test', 18:08:19 INFO - 'SHELL': '/bin/bash', 18:08:19 INFO - 'SHLVL': '1', 18:08:19 INFO - 'TERM': 'linux', 18:08:19 INFO - 'TMOUT': '86400', 18:08:19 INFO - 'USER': 'cltbld', 18:08:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:19 INFO - '_': '/tools/buildbot/bin/python'} 18:08:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:19 INFO - Downloading/unpacking psutil>=3.1.1 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:08:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:21 INFO - Installing collected packages: psutil 18:08:21 INFO - Running setup.py install for psutil 18:08:21 INFO - building 'psutil._psutil_linux' extension 18:08:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:08:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:08:22 INFO - building 'psutil._psutil_posix' extension 18:08:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:08:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:08:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:22 INFO - Successfully installed psutil 18:08:22 INFO - Cleaning up... 18:08:22 INFO - Return code: 0 18:08:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:22 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:08:22 INFO - Using env: {'DISPLAY': ':0', 18:08:22 INFO - 'HOME': '/home/cltbld', 18:08:22 INFO - 'LANG': 'en_US.UTF-8', 18:08:22 INFO - 'LANGUAGE': 'en_US:en', 18:08:22 INFO - 'LOGNAME': 'cltbld', 18:08:22 INFO - 'MAIL': '/var/mail/cltbld', 18:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:22 INFO - 'MOZ_NO_REMOTE': '1', 18:08:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:22 INFO - 'NO_EM_RESTART': '1', 18:08:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:22 INFO - 'PWD': '/builds/slave/test', 18:08:22 INFO - 'SHELL': '/bin/bash', 18:08:22 INFO - 'SHLVL': '1', 18:08:22 INFO - 'TERM': 'linux', 18:08:22 INFO - 'TMOUT': '86400', 18:08:22 INFO - 'USER': 'cltbld', 18:08:22 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:22 INFO - '_': '/tools/buildbot/bin/python'} 18:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:08:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:08:24 INFO - Installing collected packages: mozsystemmonitor 18:08:24 INFO - Running setup.py install for mozsystemmonitor 18:08:24 INFO - Successfully installed mozsystemmonitor 18:08:24 INFO - Cleaning up... 18:08:24 INFO - Return code: 0 18:08:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:24 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:08:24 INFO - Using env: {'DISPLAY': ':0', 18:08:24 INFO - 'HOME': '/home/cltbld', 18:08:24 INFO - 'LANG': 'en_US.UTF-8', 18:08:24 INFO - 'LANGUAGE': 'en_US:en', 18:08:24 INFO - 'LOGNAME': 'cltbld', 18:08:24 INFO - 'MAIL': '/var/mail/cltbld', 18:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:24 INFO - 'MOZ_NO_REMOTE': '1', 18:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:24 INFO - 'NO_EM_RESTART': '1', 18:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:24 INFO - 'PWD': '/builds/slave/test', 18:08:24 INFO - 'SHELL': '/bin/bash', 18:08:24 INFO - 'SHLVL': '1', 18:08:24 INFO - 'TERM': 'linux', 18:08:24 INFO - 'TMOUT': '86400', 18:08:24 INFO - 'USER': 'cltbld', 18:08:24 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:24 INFO - '_': '/tools/buildbot/bin/python'} 18:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:25 INFO - Downloading/unpacking jsonschema==2.5.1 18:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:08:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:27 INFO - Downloading functools32-3.2.3-2.tar.gz 18:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:08:27 INFO - warning: no files found matching '*.txt' 18:08:27 INFO - no previously-included directories found matching 'build' 18:08:27 INFO - no previously-included directories found matching 'dist' 18:08:27 INFO - no previously-included directories found matching '.git*' 18:08:27 INFO - Installing collected packages: jsonschema, functools32 18:08:27 INFO - Running setup.py install for functools32 18:08:27 INFO - warning: no files found matching '*.txt' 18:08:27 INFO - no previously-included directories found matching 'build' 18:08:27 INFO - no previously-included directories found matching 'dist' 18:08:27 INFO - no previously-included directories found matching '.git*' 18:08:27 INFO - Successfully installed jsonschema functools32 18:08:27 INFO - Cleaning up... 18:08:27 INFO - Return code: 0 18:08:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:27 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:08:27 INFO - Using env: {'DISPLAY': ':0', 18:08:27 INFO - 'HOME': '/home/cltbld', 18:08:27 INFO - 'LANG': 'en_US.UTF-8', 18:08:27 INFO - 'LANGUAGE': 'en_US:en', 18:08:27 INFO - 'LOGNAME': 'cltbld', 18:08:27 INFO - 'MAIL': '/var/mail/cltbld', 18:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:27 INFO - 'MOZ_NO_REMOTE': '1', 18:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:27 INFO - 'NO_EM_RESTART': '1', 18:08:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:27 INFO - 'PWD': '/builds/slave/test', 18:08:27 INFO - 'SHELL': '/bin/bash', 18:08:27 INFO - 'SHLVL': '1', 18:08:27 INFO - 'TERM': 'linux', 18:08:27 INFO - 'TMOUT': '86400', 18:08:27 INFO - 'USER': 'cltbld', 18:08:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:27 INFO - '_': '/tools/buildbot/bin/python'} 18:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:08:27 INFO - Cleaning up... 18:08:27 INFO - Return code: 0 18:08:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:27 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:08:27 INFO - Using env: {'DISPLAY': ':0', 18:08:27 INFO - 'HOME': '/home/cltbld', 18:08:27 INFO - 'LANG': 'en_US.UTF-8', 18:08:27 INFO - 'LANGUAGE': 'en_US:en', 18:08:27 INFO - 'LOGNAME': 'cltbld', 18:08:27 INFO - 'MAIL': '/var/mail/cltbld', 18:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:27 INFO - 'MOZ_NO_REMOTE': '1', 18:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:27 INFO - 'NO_EM_RESTART': '1', 18:08:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:27 INFO - 'PWD': '/builds/slave/test', 18:08:27 INFO - 'SHELL': '/bin/bash', 18:08:27 INFO - 'SHLVL': '1', 18:08:27 INFO - 'TERM': 'linux', 18:08:27 INFO - 'TMOUT': '86400', 18:08:27 INFO - 'USER': 'cltbld', 18:08:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:27 INFO - '_': '/tools/buildbot/bin/python'} 18:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:28 INFO - Downloading/unpacking blobuploader==1.2.4 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - Downloading blobuploader-1.2.4.tar.gz 18:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:08:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:08:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:30 INFO - Downloading docopt-0.6.1.tar.gz 18:08:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:08:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:08:30 INFO - Running setup.py install for blobuploader 18:08:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:08:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:08:30 INFO - Running setup.py install for requests 18:08:31 INFO - Running setup.py install for docopt 18:08:31 INFO - Successfully installed blobuploader requests docopt 18:08:31 INFO - Cleaning up... 18:08:31 INFO - Return code: 0 18:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:31 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:08:31 INFO - Using env: {'DISPLAY': ':0', 18:08:31 INFO - 'HOME': '/home/cltbld', 18:08:31 INFO - 'LANG': 'en_US.UTF-8', 18:08:31 INFO - 'LANGUAGE': 'en_US:en', 18:08:31 INFO - 'LOGNAME': 'cltbld', 18:08:31 INFO - 'MAIL': '/var/mail/cltbld', 18:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:31 INFO - 'MOZ_NO_REMOTE': '1', 18:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:31 INFO - 'NO_EM_RESTART': '1', 18:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:31 INFO - 'PWD': '/builds/slave/test', 18:08:31 INFO - 'SHELL': '/bin/bash', 18:08:31 INFO - 'SHLVL': '1', 18:08:31 INFO - 'TERM': 'linux', 18:08:31 INFO - 'TMOUT': '86400', 18:08:31 INFO - 'USER': 'cltbld', 18:08:31 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:31 INFO - '_': '/tools/buildbot/bin/python'} 18:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:31 INFO - Running setup.py (path:/tmp/pip-J2WoVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:31 INFO - Running setup.py (path:/tmp/pip-VtizNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:31 INFO - Running setup.py (path:/tmp/pip-wKqPL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:31 INFO - Running setup.py (path:/tmp/pip-9VrAYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:31 INFO - Running setup.py (path:/tmp/pip-o3VOyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:32 INFO - Running setup.py (path:/tmp/pip-X76DeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:32 INFO - Running setup.py (path:/tmp/pip-SCaauI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:32 INFO - Running setup.py (path:/tmp/pip-1m7Ueo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:32 INFO - Running setup.py (path:/tmp/pip-gkdB78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:32 INFO - Running setup.py (path:/tmp/pip-93uAja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:32 INFO - Running setup.py (path:/tmp/pip-HzSWOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:32 INFO - Running setup.py (path:/tmp/pip-ewfUih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:32 INFO - Running setup.py (path:/tmp/pip-nYhNs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:32 INFO - Running setup.py (path:/tmp/pip-RvbQvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:32 INFO - Running setup.py (path:/tmp/pip-Fkj1_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:32 INFO - Running setup.py (path:/tmp/pip-vArZ6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:32 INFO - Running setup.py (path:/tmp/pip-7R6zGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:08:32 INFO - Running setup.py install for manifestparser 18:08:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:08:32 INFO - Running setup.py install for mozcrash 18:08:33 INFO - Running setup.py install for mozdebug 18:08:33 INFO - Running setup.py install for mozdevice 18:08:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Running setup.py install for mozfile 18:08:33 INFO - Running setup.py install for mozhttpd 18:08:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Running setup.py install for mozinfo 18:08:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Running setup.py install for mozInstall 18:08:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Running setup.py install for mozleak 18:08:33 INFO - Running setup.py install for mozlog 18:08:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:08:33 INFO - Running setup.py install for moznetwork 18:08:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Running setup.py install for mozprocess 18:08:34 INFO - Running setup.py install for mozprofile 18:08:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Running setup.py install for mozrunner 18:08:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Running setup.py install for mozscreenshot 18:08:34 INFO - Running setup.py install for moztest 18:08:34 INFO - Running setup.py install for mozversion 18:08:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:08:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:08:34 INFO - Cleaning up... 18:08:34 INFO - Return code: 0 18:08:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:34 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:08:34 INFO - Using env: {'DISPLAY': ':0', 18:08:34 INFO - 'HOME': '/home/cltbld', 18:08:34 INFO - 'LANG': 'en_US.UTF-8', 18:08:34 INFO - 'LANGUAGE': 'en_US:en', 18:08:34 INFO - 'LOGNAME': 'cltbld', 18:08:34 INFO - 'MAIL': '/var/mail/cltbld', 18:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:34 INFO - 'MOZ_NO_REMOTE': '1', 18:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:34 INFO - 'NO_EM_RESTART': '1', 18:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:34 INFO - 'PWD': '/builds/slave/test', 18:08:34 INFO - 'SHELL': '/bin/bash', 18:08:34 INFO - 'SHLVL': '1', 18:08:34 INFO - 'TERM': 'linux', 18:08:34 INFO - 'TMOUT': '86400', 18:08:34 INFO - 'USER': 'cltbld', 18:08:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:34 INFO - '_': '/tools/buildbot/bin/python'} 18:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Running setup.py (path:/tmp/pip-VHe43S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:34 INFO - Running setup.py (path:/tmp/pip-IFg8yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:35 INFO - Running setup.py (path:/tmp/pip-9t7XK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:35 INFO - Running setup.py (path:/tmp/pip-XXQfI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:35 INFO - Running setup.py (path:/tmp/pip-7ukURS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:35 INFO - Running setup.py (path:/tmp/pip-_kkbvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:35 INFO - Running setup.py (path:/tmp/pip-I6gaxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:35 INFO - Running setup.py (path:/tmp/pip-H5pVFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:35 INFO - Running setup.py (path:/tmp/pip-OcsTmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:35 INFO - Running setup.py (path:/tmp/pip-MVadHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Running setup.py (path:/tmp/pip-4gBE8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Running setup.py (path:/tmp/pip-RXSSrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Running setup.py (path:/tmp/pip-DuwpaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Running setup.py (path:/tmp/pip-kqYVdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Running setup.py (path:/tmp/pip-2qk3U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:35 INFO - Running setup.py (path:/tmp/pip-q5hnnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:36 INFO - Running setup.py (path:/tmp/pip-9P1IDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:08:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:08:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:08:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:38 INFO - Downloading blessings-1.6.tar.gz 18:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:08:38 INFO - Installing collected packages: blessings 18:08:38 INFO - Running setup.py install for blessings 18:08:38 INFO - Successfully installed blessings 18:08:38 INFO - Cleaning up... 18:08:38 INFO - Return code: 0 18:08:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:08:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:38 INFO - Reading from file tmpfile_stdout 18:08:38 INFO - Current package versions: 18:08:38 INFO - argparse == 1.2.1 18:08:38 INFO - blessings == 1.6 18:08:38 INFO - blobuploader == 1.2.4 18:08:38 INFO - docopt == 0.6.1 18:08:38 INFO - functools32 == 3.2.3-2 18:08:38 INFO - jsonschema == 2.5.1 18:08:38 INFO - manifestparser == 1.1 18:08:38 INFO - mozInstall == 1.12 18:08:38 INFO - mozcrash == 1.0 18:08:38 INFO - mozdebug == 0.1 18:08:38 INFO - mozdevice == 0.49 18:08:38 INFO - mozfile == 1.2 18:08:38 INFO - mozhttpd == 0.7 18:08:38 INFO - mozinfo == 0.9 18:08:38 INFO - mozleak == 0.1 18:08:38 INFO - mozlog == 3.4 18:08:38 INFO - moznetwork == 0.27 18:08:38 INFO - mozprocess == 0.23 18:08:38 INFO - mozprofile == 0.28 18:08:38 INFO - mozrunner == 6.13 18:08:38 INFO - mozscreenshot == 0.1 18:08:38 INFO - mozsystemmonitor == 0.3 18:08:38 INFO - moztest == 0.7 18:08:38 INFO - mozversion == 1.4 18:08:38 INFO - psutil == 3.1.1 18:08:38 INFO - requests == 1.2.3 18:08:38 INFO - wsgiref == 0.1.2 18:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 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 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:08:38 INFO - Using env: {'DISPLAY': ':0', 18:08:38 INFO - 'HOME': '/home/cltbld', 18:08:38 INFO - 'LANG': 'en_US.UTF-8', 18:08:38 INFO - 'LANGUAGE': 'en_US:en', 18:08:38 INFO - 'LOGNAME': 'cltbld', 18:08:38 INFO - 'MAIL': '/var/mail/cltbld', 18:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:38 INFO - 'MOZ_NO_REMOTE': '1', 18:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:38 INFO - 'NO_EM_RESTART': '1', 18:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:38 INFO - 'PWD': '/builds/slave/test', 18:08:38 INFO - 'SHELL': '/bin/bash', 18:08:38 INFO - 'SHLVL': '1', 18:08:38 INFO - 'TERM': 'linux', 18:08:38 INFO - 'TMOUT': '86400', 18:08:38 INFO - 'USER': 'cltbld', 18:08:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:38 INFO - '_': '/tools/buildbot/bin/python'} 18:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:08:38 INFO - Cleaning up... 18:08:38 INFO - Return code: 0 18:08:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f704b06ee30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1229120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, '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': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:08:38 INFO - Using env: {'DISPLAY': ':0', 18:08:38 INFO - 'HOME': '/home/cltbld', 18:08:38 INFO - 'LANG': 'en_US.UTF-8', 18:08:38 INFO - 'LANGUAGE': 'en_US:en', 18:08:38 INFO - 'LOGNAME': 'cltbld', 18:08:38 INFO - 'MAIL': '/var/mail/cltbld', 18:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:38 INFO - 'MOZ_NO_REMOTE': '1', 18:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:38 INFO - 'NO_EM_RESTART': '1', 18:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:38 INFO - 'PWD': '/builds/slave/test', 18:08:38 INFO - 'SHELL': '/bin/bash', 18:08:38 INFO - 'SHLVL': '1', 18:08:38 INFO - 'TERM': 'linux', 18:08:38 INFO - 'TMOUT': '86400', 18:08:38 INFO - 'USER': 'cltbld', 18:08:38 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:38 INFO - '_': '/tools/buildbot/bin/python'} 18:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:08:39 INFO - Cleaning up... 18:08:39 INFO - Return code: 0 18:08:39 INFO - Running post-action listener: _resource_record_post_action 18:08:39 INFO - Running post-action listener: _start_resource_monitoring 18:08:39 INFO - Starting resource monitoring. 18:08:39 INFO - [mozharness: 2016-12-09 02:08:39.072881Z] Finished create-virtualenv step (success) 18:08:39 INFO - [mozharness: 2016-12-09 02:08:39.073359Z] Running install step. 18:08:39 INFO - Running pre-action listener: _resource_record_pre_action 18:08:39 INFO - Running main action method: install 18:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:39 INFO - Reading from file tmpfile_stdout 18:08:39 INFO - Detecting whether we're running mozinstall >=1.0... 18:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:08:39 INFO - Reading from file tmpfile_stdout 18:08:39 INFO - Output received: 18:08:39 INFO - Usage: mozinstall [options] installer 18:08:39 INFO - Options: 18:08:39 INFO - -h, --help show this help message and exit 18:08:39 INFO - -d DEST, --destination=DEST 18:08:39 INFO - Directory to install application into. [default: 18:08:39 INFO - "/builds/slave/test"] 18:08:39 INFO - --app=APP Application being installed. [default: firefox] 18:08:39 INFO - mkdir: /builds/slave/test/build/application 18:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:08:56 INFO - Reading from file tmpfile_stdout 18:08:56 INFO - Output received: 18:08:56 INFO - /builds/slave/test/build/application/firefox/firefox 18:08:56 INFO - Running post-action listener: _resource_record_post_action 18:08:56 INFO - [mozharness: 2016-12-09 02:08:56.332009Z] Finished install step (success) 18:08:56 INFO - [mozharness: 2016-12-09 02:08:56.332279Z] Running run-tests step. 18:08:56 INFO - Running pre-action listener: _resource_record_pre_action 18:08:56 INFO - Running main action method: run_tests 18:08:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:08:56 INFO - Python 2.7.3 18:08:56 INFO - Return code: 0 18:08:56 INFO - grabbing minidump binary from tooltool 18:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:56 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 0x12b9f20>, '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 0x1226c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1226f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:08:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:08:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:08:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:08:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:08:56 INFO - Return code: 0 18:08:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:08:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:08:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:08:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:08:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:08:56 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:08:56 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:08:56 INFO - Using env: {'DISPLAY': ':0', 18:08:56 INFO - 'HOME': '/home/cltbld', 18:08:56 INFO - 'LANG': 'en_US.UTF-8', 18:08:56 INFO - 'LANGUAGE': 'en_US:en', 18:08:56 INFO - 'LOGNAME': 'cltbld', 18:08:56 INFO - 'MAIL': '/var/mail/cltbld', 18:08:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:08:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:56 INFO - 'MOZ_NO_REMOTE': '1', 18:08:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:08:56 INFO - 'NO_EM_RESTART': '1', 18:08:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:56 INFO - 'PWD': '/builds/slave/test', 18:08:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:08:56 INFO - 'SHELL': '/bin/bash', 18:08:56 INFO - 'SHLVL': '1', 18:08:56 INFO - 'TERM': 'linux', 18:08:56 INFO - 'TMOUT': '86400', 18:08:56 INFO - 'USER': 'cltbld', 18:08:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690', 18:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:56 INFO - '_': '/tools/buildbot/bin/python'} 18:08:56 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/1481240094/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:08:56 INFO - mozversion application_buildid: 20161208153454 18:08:56 INFO - mozversion application_changeset: 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 18:08:56 INFO - mozversion application_display_name: Nightly 18:08:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:08:56 INFO - mozversion application_name: Firefox 18:08:56 INFO - mozversion application_remotingname: firefox 18:08:56 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:08:56 INFO - mozversion application_vendor: Mozilla 18:08:56 INFO - mozversion application_version: 53.0a1 18:08:56 INFO - mozversion platform_buildid: 20161208153454 18:08:56 INFO - mozversion platform_changeset: 28d3c72eaecdc719fa4a01d5bb2d345b216070a9 18:08:56 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:08:56 INFO - mozversion platform_version: 53.0a1 18:08:56 INFO - using testdate: 1481249336 18:08:56 INFO - actual date: 1481249336 18:08:56 INFO - starting webserver on 'localhost:43890' 18:08:56 INFO - SUITE-START | Running 2 tests 18:08:56 INFO - TEST-START | dromaeo_css 18:08:56 INFO - operating with platform_type : linux_ 18:08:56 INFO - Initialising browser for dromaeo_css test... 18:08:56 INFO - TEST-INFO | started process 1848 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnK5hDI/profile http://localhost:43890/getInfo.html) 18:09:00 INFO - PROCESS | 1848 | __metrics Screen width/height:1600/1200 18:09:00 INFO - PROCESS | 1848 | colorDepth:24 18:09:00 INFO - PROCESS | 1848 | Browser inner width/height: 1024/697 18:09:00 INFO - PROCESS | 1848 | __metrics 18:09:01 INFO - PROCESS | 1848 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:09:01 INFO - PROCESS | 1848 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:09:01 INFO - PROCESS | 1848 | console.error: PushService: 18:09:01 INFO - PROCESS | 1848 | stateChangeProcessEnqueue: Error transitioning state 18:09:01 INFO - PROCESS | 1848 | UnknownError 18:09:02 INFO - TEST-INFO | 1848: exit 0 18:09:02 INFO - Browser initialized. 18:09:02 INFO - Running cycle 1/1 for dromaeo_css test... 18:09:02 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnK5hDI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:14 INFO - PROCESS | 1937 | RSS: Main: 172421120 18:09:14 INFO - PROCESS | 1937 | 18:10:05 INFO - PROCESS | 1937 | 1481249405617 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:12:16 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43890/tests/dromaeo/cssquery-ext.html) 18:12:18 INFO - PROCESS | 1937 | RSS: Main: 339898368 18:12:18 INFO - PROCESS | 1937 | 18:15:21 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-ext.html (next: http://localhost:43890/tests/dromaeo/cssquery-jquery.html) 18:15:21 INFO - PROCESS | 1937 | RSS: Main: 272068608 18:15:21 INFO - PROCESS | 1937 | 18:18:23 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43890/tests/dromaeo/cssquery-mootools.html) 18:18:24 INFO - PROCESS | 1937 | RSS: Main: 219586560 18:18:24 INFO - PROCESS | 1937 | 18:21:26 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43890/tests/dromaeo/cssquery-prototype.html) 18:21:27 INFO - PROCESS | 1937 | RSS: Main: 250761216 18:21:27 INFO - PROCESS | 1937 | 18:24:29 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43890/tests/dromaeo/cssquery-yui.html) 18:24:30 INFO - PROCESS | 1937 | RSS: Main: 220217344 18:24:30 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:43890/tests/dromaeo/cssquery-yui.html (next: http://localhost:43890/tests/dromaeo/cssquery-dojo.html) 18:27:33 INFO - PROCESS | 1937 | RSS: Main: 313319424 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | __start_tp_report 18:27:33 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 18:27:33 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 18:27:33 INFO - PROCESS | 1937 | |i|pagename|runs| 18:27:33 INFO - PROCESS | 1937 | |0;dojo.html;3241.173500757305;3255.300110476247;3271.8854840080603;3303.785253958494;3317.9170520736984;5271.604629652777;5280.471952804718;5369.114096174131;5377.085895397782;5381.488758567934;5333.679979201251;5343.465653434655;5352.197170424438;5358.544523715489;5368.489993450626;4858.902821943559;4897.975510122445;4906.435759887605;4925.064237794808;4961.900761984758;4885.267209918523;4940.678855874375;4941;4947.851564453073;4949.579285760715;4825.20384136617;4858.441279252896;4860.222364421693;4870.4885986971385;4930.359053323081;4893.7063776173545;4903.362562866841;4909.459959404463;4915.852524424237;4956.033573453178;4803.375561177025;4803.519648035169;4826.0347930414055;4837.564619184255;4865.756712164378;5233.058049551079;5257.605679574009;5261.526462618344;5271.947280527184;5345.091334473149;5616.943830561683;5675.5459563234845;5675.914861277084;5680.091185410356;5680.715964201815;5571.359293681238;5613.971930140364;5694.629849059798;5703.486686198221;5747.396523365028;5351.973240133813;5374.381946076252;5386.973065134649;5428.484293992104;5445.809396671098;5822.679752613562;5834.504067154255;5835.9708201458725;5842.182094506772;5844.649321040752;5520.199571062173;5573.303337082864;5580.49775520197;5582.358028826737;5636.85907852307;5596.384397716248;5614.719264036864;5637.633553818989;5649.745761440745;5654.971725141348;5612.410696876769;5654.802081927113;5659.377468478464;5697;5713.542916566665;5654.236678048411;5659.5755318351285;5661.490465858093;5665.943340566541;5709.800156994485;4337.349397590387;4412.183746006999;4414.271645178574;4425.822967081345;4429.047754732748;4021.075152714892;4056.229316429869;4057.5130984282073;4062.614051665087;4065.6950728694874;5430.049741295336;5433.918491222635;5471.452854714496;5503.229547863302;5509.338879334506;5744.770209191669;5750.0512415450985;5752.050911599623;5790.739416726257;5803.042497987784;5229.895402092013;5268.341457317835;5356.91964620531;5389.730513474311;5460;4975.776090075955;4998.775055122528;5005.524475174852;5029.874253143701;5048.242763585492;5946.494547963385;5952.732127054206;5955.166276721263;5955.37468565803;5957.523398128139;5562.054450743302;5584.218209450599;5733.0540460823495;5742.655440673573;5746.454086861741;5885.941140588539;5902.940970590239;5917.733701983507;5956.315023772322;6013.488853447506;5599.356074051373;5619.775208991595;5628.8311350657905;5630.155476678531;5652.85867853307;5609.186667933209;5716.8570785729025;5717.942820571741;5718.742656580547;5725.198472213977;5552.250446189712;5567.387587365468;5605.691686957093;5623.297087864017;5694.629849059798;5615.691136987505;5617.32592088952;5621.213030175697;5623.2408624835125;5624.521915637217;5509.256250406143;5547.0570003098765;5553.6390134641115;5574.219609254788;5578.386377498391;5498.890022199614;5545.7781688732;5547.528460080777;5617.859553511195;5620.213170156263;5401.540869026177;5438.510534051954;5438.728063596725;5445.237666726583;5452.781888724565 18:27:33 INFO - PROCESS | 1937 | |1;ext.html;538.2789289386525;589.2840199158022;607.7386723708803;624.8437890527371;629.6473974574242;31161.532577011334;31700.68299317009;40881.97795055121;41765.582344176546;41983.370249446285;39097.609023909754;42596.36105458413;42637.57362426375;42661.786691066576;42746.35880461796;8384.455010424319;8534.658613655478;8544.829103417927;8546.230839224469;8552.35857310701;7736.419768517384;7908.44640875139;7915.327197188246;8097.635606397696;8324.167583241657;9940.850887236697;10175.796484070313;10175.898241017569;10408.583656653762;10416.218783591223;10097.293189476739;10162.39025658463;10175.542100605491;10177.592896284139;10180.490975451272;9868.753281167956;9954.452505112216;9956.452395118265;9961.053699898497;9964.90035099647;39792.801035994926;39955.40066898999;40010;40087.19825603501;40089.59910400888;3369.552191107739;3486.1882581512837;3530.6292839252023;3581.8209089545417;3742.7192960527796;30288.70454613035;30493.69506304953;30769.230769230726;31128.533072003713;32164.6783532164;4059.878203653895;4118.629323360882;4163.542010378856;4306.5262821089655;4348.152110338485;321413;322621;324559;326590;328456.9999999976;53134;53551.99999999922;53600;53608.46391536113;53866.73066634642;56600;57126.143107853415;57142.142867857016;57225;57290.140647890315;15820.288087036111;16566;16566.751498727528;16567.337306507845;16582.75125873113;11241.55033798639;11257.21199516026;11275.210735248455;11278.54885804575;11355.716107097389;13830.37763300654;14153.433862645585;14981.850181498046;15423.151726654918;15482.148481833607;5722.284714410699;5758.913616295759;5762.711864406763;5941.940580594225;5944.286685597756;940.1538615246196;1016.7305663999047;1051.2746205118444;1112.0880877680224;1130.796456637813;30685.69314306873;31269.96924363229;31274.687253127177;31678.366432671217;31815.204619884687;1078.8489611454402;1103.5254840418697;1109.7669489407315;1131.4795194210756;1178.3106882473678;15865.365385384686;16421.58946026335;16474.58813529671;16569.857535616095;16796.748048779278;133372;134501.3274933619;134510;134755.32622336826;135425;55171.448285517436;55215.72392138094;55217.44782552203;55306.446935530126;55360.72319638456;1305.3081866610712;1363.1412210307442;1374.6288502104576;1388.5834249725042;1389.4928351151757;29301.85349073241;30586.235344116576;30833.229169270948;33483.16292092717;33885;54048.729756350964;54606.72696636491;54685;54722;54804.327394165644;409658;415509;415537;416005;417659;24532;25067;26369.20892373232;27218.591721124594;27665.58501622437;40785.59214407818;44729;45113.77443112763;45228.321575177055;45493.54506454893;38304.80847595744;38311;39285;39388.60611393849;40440.19119617532;38176.809115954245;38391.6160838388;38591.42112868365;38765;39363.409548856216;38164.427533586466;38814.4177837327;39381;39494.80252598719;39614.207715846096;32794;34774.304513909075;34990.12524686801;35750.8212458936;35781;25963;26414.603780942918;26861.597076044265;27413.31466713348;27558.5866212011 18:27:33 INFO - PROCESS | 1937 | |2;jquery.html;378.3983466288602;378.76138033039183;396.4370593756865;398.5257543523207;415.31265755175195;13923.582292531215;14589.635259118528;17614.91192544037;18899.433017009495;18905.905470472644;15140;17479.47561573155;17879.374221902235;18506.352277670285;18524.722129168073;5350.464953504647;5558.249636299109;5562.554995600342;5570.359408668014;5576.553875689955;5254.710990895499;5502.972485137569;5522.116461366182;5541.556675465973;5541.833744987657;5510.724463776815;5510.9448905510835;5512.035393806088;5513.7794488220425;5515.586331025169;5315.707636080014;5497.202905578689;5520.447955204488;5536.197251398545;5536.446355364431;4737.147313483571;4980.103581355354;4982.679589908677;4983.302337672729;4986.601071914238;21383.35849924505;21749.34751957444;21838;21896.12415503378;21919.890400548054;2276.146445083094;2286.142696488817;2416.359664688859;2531.088808029108;2816.9295767605768;9728.854067188999;10069.798604027952;10386.844197337046;13205.603831885059;14403.351849166813;1823.088455772114;1872.4663470910725;1880.0411789987068;1922.73099754163;1959.6276707425543;73747.67169975827;76607.23392766056;78025.60987195028;78723.60638196829;79885.20114798835;9185.77035574116;9495.620175193053;9620.807583848424;9707.369021013612;10008.649697260707;12849.4217760201;13247;13296.534621288401;13318.134321269086;13327.600601936658;7777.688892444239;7790.610469476504;7944.920550794418;8036.67853285862;8047.476914000571;7881.487703299267;7888;7905.328047115944;8087.878681819778;8120.75637730869;4025.718199725991;4074.30736774749;4095.119549296856;4099.97950010248;4169.082801783603;1900.5248687828043;1908.1985566062287;1958.520162560182;1992.3325685895097;2026.7567891853073;935.3125452792192;943.0852073488705;970.5341436110565;978.8727465429448;1003.2575893838507;17479;19034.428967131007;19096.80903190978;19198.13608387626;19320.32378866761;943.1464524605243;944.4994153098869;990.2672022703148;1006.0442579549457;1014.6702321745462;8430.536320502431;10432.73918152037;10734.336177892439;10747.946260268805;10760.946195268974;295463;297704;382431;384093;384582;65413.15042194967;75407.86888196682;85559.57220213859;88386.55806721054;92119;999.8150342186718;1013.4983183324155;1048.3552615141784;1051.2037978975886;1052.610534102387;16613.335466581684;17070.658586827947;17538.561535961704;17543.210555524776;17746.46760597184;38819;39693.404598930465;40302.798486007385;40806.591934081465;40824.79587602043;54874.45125548853;55958;56510;57484.42515574791;58431;7738.2261773822165;7803.7268695595385;8141.592920354077;8187.672493100209;8532.658693652433;11817.350045747566;12703.174293670882;12890.228600850862;12965.416556254802;13446.058775885595;11263.267887587608;11313.660590182308;11385.430728463709;11668.183227174017;11834.408279586158;11017.338959662446;11203.551857925591;11262.7747445049;11283;11815.17293789461;10711.500389945259;11003.22977391575;11286.774264514499;11621.593244236412;11858.703532411759;8368.20502052304;8477.915220847712;8490.41042482049;8642.308615310634;8756.956215218883;11021.283616564897;11207.271527350696;11463.942680286546;11497.885021149681;11693 18:27:33 INFO - PROCESS | 1937 | |3;mootools.html;1336.0714303559018;1403.4386245501798;1450.209635748517;1575.566719152233;1730.9567260818478;6900.2754710755335;7751.224877512253;7965.60171991401;7965.999999999993;8155.143709910463;8013.439059265839;8044.91955080449;8049.034115906099;8101.675932962689;8105.108438071822;1862.683343831545;1917.0127384397047;2081.09472379515;2120.416885356524;2126.1176611706123;2083.0626218201796;2151.139544182324;2153.6984822124914;2158.3956492182215;2159.654455287154;2127.6595744680853;2136.5620047890147;2164.5346250556127;2166.2093335932427;2169.783021697833;2098.4124445155303;2112.7464704235513;2115.608612406694;2116.1323857218467;2118.5868755592664;2075.4603803011173;2120.968185477219;2127.585120901425;2127.595756806217;2135.263334149716;7830.960845195737;7881.527108373516;7899.605019748989;7901.446898717092;7903.051633803981;4874.366332376804;5035.697858128524;5036.395632524121;5043.293938848564;5045.571126454256;3054.5570892220503;3217.887373941901;3267.036224313833;3270.934581308384;3287.260366417561;954.2699834626517;1021.1881554164457;1031.1493018259891;1040.3861721584226;1075.537518866887;10632.362058276529;10741.194410419172;10968.890311096866;11050.889491105147;11164;4194.2869712149;4215.051613387025;4234.9364759528635;4238.237117318912;4322.459692538433;9560.139587437025;9575.952120239353;9588.66439674608;9591.136797688241;9598.232141428669;4782.426108866958;4854.490278520775;4864.854054378375;4903.166461701519;4927.088488629615;4168.979155104205;4264.893377665521;4424.269995450714;4480.7535585542455;4539.341795439643;3471.864803146047;3537.239493508908;3543.2027793746715;3592.9101772455374;3603.117236277129;1092.4537731134433;1113.704868209925;1126.267925848189;1130.7908037013015;1133.1161693878787;908.2416182487565;932.785459344341;933.4539294512786;939.2345238630494;953.1278879825021;5388.5419739321815;5608.270924779753;5675.829725108253;5816.360200377955;5847.239858818326;895.9372843900865;897.8294124116397;931.1154403316876;960.2701946520694;972.9805403891883;3789.2989796887664;3795.582485926546;3861.806909654506;3877.65101140893;3919.9020024499614;9386.436813791195;9460.95269523648;9470.621175152917;9471;9475.241980641533;9334.813303733887;9375.5312234388;9387.5306234688;9393.389429687048;9403.952980235055;7059.2940705928995;7343.760153153233;7361.6319184040585;7377.88933166003;7445.739399121005;5214.921776173436;5285.550728187994;5366.356037275552;5558.610897237155;5888;8444.873326899979;8471.745847624581;8521;8542.145785421408;8594.097619750086;8602.18279263469;8693.826123477622;8697.173768491983;8731.214190722865;8783.648654054023;6386.201724784402;6545.363659085228;6545.887199176056;6864.862702745817;7087.184973728087;7132.607706576188;7196.208417074223;7371.8157046074275;7495.962520187364;7566.167721550515;6755.4257888080065;6922.169339679271;6952.791416257521;6986.54587451814;7038.577685338896;6606.47148228131;6816.931830681629;6822.556533825285;6830.282820303796;6857.5199736018;6054.969725151346;6055.212822333068;6209.472194863487;6268.153799237045;6364.93635063662;4678.719276843401;4751.7861696224445;4754.786034628519;4772.832950846704;4849.224124140121;4599.655025873006;4725.905481890274;4728.148933192058;4815.638827087912;4834.129856625841 18:27:33 INFO - PROCESS | 1937 | |4;prototype.html;325.4369940003795;333.2718011145647;335.9479280711489;340.5810852651239;343.78341644763793;5024.723640199788;5157.349648112606;5230.790768369266;5279.683219006857;5284.2866213061225;5019.924701129486;5076.502431782624;5099.827039780844;5151.042603810556;5203.141481655532;4093.2836753568154;4109.6506796922295;4113.218488487193;4123.381492776078;4129.132882094764;3888.863889763859;3919.7060220483436;4110.671146308303;4130.545639979599;4149.875503734893;4069.9593004069875;4095.897602559945;4102.031025107935;4107.507099148106;4108.157827645326;4099.3031184698675;4107.938380924288;4110.116324990141;4111.403846442264;4122.237386083569;3674.5747806447885;3677.6322367763278;3679.6136405677285;3680.9815950920342;3700.703943684499;4945.7774400152075;4998.425181104183;5010.849674509734;5012.774425150877;5055.823046193365;4633.181491249097;4654.650901182425;4659.483878705495;4682.391289132425;4683.555062269079;5300.178472336794;5358.732063396854;5371;5383.650062745909;5395.406505284415;3870.4581358609817;3956.358111383772;3978.781167035812;3985.501812273466;3995.141044675409;5501.477359650827;5515.586331025189;5527.778888844441;5532.727472681306;5546.4453554644615;4022.255882661717;4025.5571887092397;4029.637332640018;4039.8788036358956;4090.6727461802984;4048.2713111640187;4083.224187404384;4099.528554216244;4101.343784994387;4140.565240649748;4582.381378513925;4622.514635963243;4633.235516139866;4652.790624421909;4653.162430762428;5383.703896285742;5415.296011518478;5427.213054107132;5428.67427954324;5449.536789372948;1328.7674656934998;1364.413302280029;1370.4038743146764;1387.8265216847894;1405.1358414574963;943.5535285174805;948.1656142594472;954.1698722111669;958.6309270930742;964.647903515226;5055.974720126376;5074.847754567369;5077.1876499759865;5081.618878584121;5098.107831129538;5265.420803711589;5335.706536140549;5380.650257733313;5399.946000540023;5411.563657077705;4858.416989961227;4968.950310496921;5026.547610715053;5057.342545469139;5058.342415485963;2809.2415047936943;2819.6052552642645;2837.1274958527683;2839.829610223393;2859.3566447549133;5508.614396992252;5541.168824676251;5545.7781688732;5568.832935011957;5581.469760372758;5238.68567885928;5419.620626556182;5435.7825686972865;5448.237246785453;5521.282233309644;5441.782328706887;5491.313585801775;5510.586705997066;5512.6692398456225;5550.7779688812;5363.383210930637;5366.597505187048;5376.623636345418;5396.919046214389;5408.296921400351;5297.417284098669;5559.694216818114;5580;5584.609077364708;5598.3282006159525;5805.564582656233;5821.883562328645;5829.708514574169;5841.386654401226;5845.88928103659;3986.661133803659;4033.3546632538655;4034.23349563582;4041.090754580196;4058.7970601470397;5573.191887176417;5576.358718747396;5620.353659329229;5647.3505546861;5653.208550802973;5519.172124181405;5521.861953451196;5554.167124848709;5574.275344205227;5605.215269862141;5321.36143662763;5478.150886612581;5511.751971161227;5518.392976772471;5531.529819965348;5431.918521222264;5450.691833959901;5451.890962180813;5452.7546260417585;5460.099083651312;5563.13771365439;5595.496405323541;5639.943600563942;5640.520555752807;5671.971640141773;5301.5493683037375;5349.518543331119;5405.297311349499;5418.647787893774;5440.265564148947 18:27:33 INFO - PROCESS | 1937 | |5;yui.html;661.6294874870073;669.9162604674415;715.2918610575532;777.4480119115424;811.1158836867813;15068.09591424514;16212.75680864788;16241.51275461738;16264.83735162648;16271.59321016975;14529.854701452983;14651.413943442276;14693.265336733173;14702.705945881102;14703.705925881475;652.4845372155991;667.2960027171332;763.8243204063069;769.6805825582377;783.0173132718459;778.6418247606108;780.2002946979335;788.5426452657474;788.6411682684364;788.6687591211705;831.0941074229089;835.0522157351389;837.6481877611387;838.932885369172;839.1231163434226;682.6450245872144;701.1691145992016;807.9151689072621;824.9587520623945;830.9584520773967;608.6986941464004;608.9025755879038;609.0863704443335;610.042233693102;610.0452791381474;13995.160290382508;13999.720005599933;14027.509037183752;14076.507322243775;14279.357428915724;14223.715525689428;14235.501757438544;14263;14298.428062877472;14313.42746290147;12521.93739031299;12576.434060467302;12764.680882977907;13035.674108147277;13125.54060607874;4193.593149109946;4226.619604235603;4239.978800106011;4301.075268817188;4356.89107772305;74652.62673686596;75685;76093.23906760973;76670;76909;11014;11028.172887033505;11052.557897684163;11229.66311010671;11579.421028948518;21003;21188.78811211899;21226.893865530572;21237.150513979577;21269.46826329354;7795.337396321376;7840.764777056697;7934.920650793533;7999.520028798291;8245.340372770164;4449.221386257456;4520.367148599199;4594.47163576193;4656;4668.486466488684;3437.8281085945605;3518.5425894634045;3523.7709548879243;3525.959841846661;3528.576570811519;594.9736704185245;604.5888795619021;612.5252928979987;614.4746241963057;614.7418084404561;647.3688154049764;679.4394624434861;689.8670816441836;696.4915611603558;714.7998560403096;8720.040795512488;9176.311776616647;9221.907780922105;9325.393849399767;9543.33196676226;626.6709977261883;680.993190068093;693.954892931968;699.9160100787938;710.982225444368;4413.8234470621455;4699.812007519661;4702.835400760957;4713.104510143062;4732.384789977281;83647.5817620908;84100.57949710212;85562.14437855667;85761.57119214488;86472;73540.33665118528;74352;74934.25065749382;76306;76825.23174768292;67793.98309025369;69156.6542167286;69205.65397172981;70734;72159.63920180366;8946.507942063248;9006.459612423276;9043.18611324984;9103.863442048241;9208.907910920805;47047.529524705686;47744;48588.38410351011;48968.5103148964;49834.75082624564;52067.47932520627;52152.21771673352;54272;55751.721241393534;56486;13398.26309552984;13421.463141474233;13433.932830335785;13454.932725336703;13615.93192034073;14475.638109047359;14590.416383344547;15124.319405626993;15560.995999283346;15609.063456192665;14209.289535523389;14237.145771253758;14531.200783956983;14658.413663453342;15028.549143525712;12926.870731292567;13939.651508712363;14140.20431282734;14304;14770.48303309379;14274.14355138695;14456.13263204211;14534.563963081124;14828.184449855362;15009.624759381102;11294.491747871201;11437.485313161094;11699.941500292785;11839.940800295943;12278.75442491163;11455.02169435936;11912.225705329125;12038.458269377725;12045.374578144309;12410.5656302029 18:27:33 INFO - PROCESS | 1937 | __end_tp_report 18:27:33 INFO - PROCESS | 1937 | __start_cc_report 18:27:33 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1644 18:27:33 INFO - PROCESS | 1937 | __end_cc_report 18:27:33 INFO - PROCESS | 1937 | __startTimestamp1481250453768__endTimestamp 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | ------- Summary: start ------- 18:27:33 INFO - PROCESS | 1937 | Number of tests: 6 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5318.86 Median:5520.20 stddev:559.62 (10.1%) stddev-sans-first:537.07 18:27:33 INFO - PROCESS | 1937 | Values: 3241.2 3255.3 3271.9 3303.8 3317.9 5271.6 5280.5 5369.1 5377.1 5381.5 5333.7 5343.5 5352.2 5358.5 5368.5 4858.9 4898.0 4906.4 4925.1 4961.9 4885.3 4940.7 4941.0 4947.9 4949.6 4825.2 4858.4 4860.2 4870.5 4930.4 4893.7 4903.4 4909.5 4915.9 4956.0 4803.4 4803.5 4826.0 4837.6 4865.8 5233.1 5257.6 5261.5 5271.9 5345.1 5616.9 5675.5 5675.9 5680.1 5680.7 5571.4 5614.0 5694.6 5703.5 5747.4 5352.0 5374.4 5387.0 5428.5 5445.8 5822.7 5834.5 5836.0 5842.2 5844.6 5520.2 5573.3 5580.5 5582.4 5636.9 5596.4 5614.7 5637.6 5649.7 5655.0 5612.4 5654.8 5659.4 5697.0 5713.5 5654.2 5659.6 5661.5 5665.9 5709.8 4337.3 4412.2 4414.3 4425.8 4429.0 4021.1 4056.2 4057.5 4062.6 4065.7 5430.0 5433.9 5471.5 5503.2 5509.3 5744.8 5750.1 5752.1 5790.7 5803.0 5229.9 5268.3 5356.9 5389.7 5460.0 4975.8 4998.8 5005.5 5029.9 5048.2 5946.5 5952.7 5955.2 5955.4 5957.5 5562.1 5584.2 5733.1 5742.7 5746.5 5885.9 5902.9 5917.7 5956.3 6013.5 5599.4 5619.8 5628.8 5630.2 5652.9 5609.2 5716.9 5717.9 5718.7 5725.2 5552.3 5567.4 5605.7 5623.3 5694.6 5615.7 5617.3 5621.2 5623.2 5624.5 5509.3 5547.1 5553.6 5574.2 5578.4 5498.9 5545.8 5547.5 5617.9 5620.2 5401.5 5438.5 5438.7 5445.2 5452.8 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:46676.91 Median:38170.62 stddev:83175.93 (217.9%) stddev-sans-first:83337.12 18:27:33 INFO - PROCESS | 1937 | Values: 538.3 589.3 607.7 624.8 629.6 31161.5 31700.7 40882.0 41765.6 41983.4 39097.6 42596.4 42637.6 42661.8 42746.4 8384.5 8534.7 8544.8 8546.2 8552.4 7736.4 7908.4 7915.3 8097.6 8324.2 9940.9 10175.8 10175.9 10408.6 10416.2 10097.3 10162.4 10175.5 10177.6 10180.5 9868.8 9954.5 9956.5 9961.1 9964.9 39792.8 39955.4 40010.0 40087.2 40089.6 3369.6 3486.2 3530.6 3581.8 3742.7 30288.7 30493.7 30769.2 31128.5 32164.7 4059.9 4118.6 4163.5 4306.5 4348.2 321413.0 322621.0 324559.0 326590.0 328457.0 53134.0 53552.0 53600.0 53608.5 53866.7 56600.0 57126.1 57142.1 57225.0 57290.1 15820.3 16566.0 16566.8 16567.3 16582.8 11241.6 11257.2 11275.2 11278.5 11355.7 13830.4 14153.4 14981.9 15423.2 15482.1 5722.3 5758.9 5762.7 5941.9 5944.3 940.2 1016.7 1051.3 1112.1 1130.8 30685.7 31270.0 31274.7 31678.4 31815.2 1078.8 1103.5 1109.8 1131.5 1178.3 15865.4 16421.6 16474.6 16569.9 16796.7 133372.0 134501.3 134510.0 134755.3 135425.0 55171.4 55215.7 55217.4 55306.4 55360.7 1305.3 1363.1 1374.6 1388.6 1389.5 29301.9 30586.2 30833.2 33483.2 33885.0 54048.7 54606.7 54685.0 54722.0 54804.3 409658.0 415509.0 415537.0 416005.0 417659.0 24532.0 25067.0 26369.2 27218.6 27665.6 40785.6 44729.0 45113.8 45228.3 45493.5 38304.8 38311.0 39285.0 39388.6 40440.2 38176.8 38391.6 38591.4 38765.0 39363.4 38164.4 38814.4 39381.0 39494.8 39614.2 32794.0 34774.3 34990.1 35750.8 35781.0 25963.0 26414.6 26861.6 27413.3 27558.6 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:24239.30 Median:383262.00 stddev:58676.37 (15.3%) stddev-sans-first:58813.62 18:27:33 INFO - PROCESS | 1937 | Values: 378.4 378.8 396.4 398.5 415.3 13923.6 14589.6 17614.9 18899.4 18905.9 15140.0 17479.5 17879.4 18506.4 18524.7 5350.5 5558.2 5562.6 5570.4 5576.6 5254.7 5503.0 5522.1 5541.6 5541.8 5510.7 5510.9 5512.0 5513.8 5515.6 5315.7 5497.2 5520.4 5536.2 5536.4 4737.1 4980.1 4982.7 4983.3 4986.6 21383.4 21749.3 21838.0 21896.1 21919.9 2276.1 2286.1 2416.4 2531.1 2816.9 9728.9 10069.8 10386.8 13205.6 14403.4 1823.1 1872.5 1880.0 1922.7 1959.6 73747.7 76607.2 78025.6 78723.6 79885.2 9185.8 9495.6 9620.8 9707.4 10008.6 12849.4 13247.0 13296.5 13318.1 13327.6 7777.7 7790.6 7944.9 8036.7 8047.5 7881.5 7888.0 7905.3 8087.9 8120.8 4025.7 4074.3 4095.1 4100.0 4169.1 1900.5 1908.2 1958.5 1992.3 2026.8 935.3 943.1 970.5 978.9 1003.3 17479.0 19034.4 19096.8 19198.1 19320.3 943.1 944.5 990.3 1006.0 1014.7 8430.5 10432.7 10734.3 10747.9 10760.9 295463.0 297704.0 382431.0 384093.0 384582.0 65413.2 75407.9 85559.6 88386.6 92119.0 999.8 1013.5 1048.4 1051.2 1052.6 16613.3 17070.7 17538.6 17543.2 17746.5 38819.0 39693.4 40302.8 40806.6 40824.8 54874.5 55958.0 56510.0 57484.4 58431.0 7738.2 7803.7 8141.6 8187.7 8532.7 11817.4 12703.2 12890.2 12965.4 13446.1 11263.3 11313.7 11385.4 11668.2 11834.4 11017.3 11203.6 11262.8 11283.0 11815.2 10711.5 11003.2 11286.8 11621.6 11858.7 8368.2 8477.9 8490.4 8642.3 8757.0 11021.3 11207.3 11463.9 11497.9 11693.0 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5202.62 Median:5325.95 stddev:2864.70 (53.8%) stddev-sans-first:2858.00 18:27:33 INFO - PROCESS | 1937 | Values: 1336.1 1403.4 1450.2 1575.6 1731.0 6900.3 7751.2 7965.6 7966.0 8155.1 8013.4 8044.9 8049.0 8101.7 8105.1 1862.7 1917.0 2081.1 2120.4 2126.1 2083.1 2151.1 2153.7 2158.4 2159.7 2127.7 2136.6 2164.5 2166.2 2169.8 2098.4 2112.7 2115.6 2116.1 2118.6 2075.5 2121.0 2127.6 2127.6 2135.3 7831.0 7881.5 7899.6 7901.4 7903.1 4874.4 5035.7 5036.4 5043.3 5045.6 3054.6 3217.9 3267.0 3270.9 3287.3 954.3 1021.2 1031.1 1040.4 1075.5 10632.4 10741.2 10968.9 11050.9 11164.0 4194.3 4215.1 4234.9 4238.2 4322.5 9560.1 9576.0 9588.7 9591.1 9598.2 4782.4 4854.5 4864.9 4903.2 4927.1 4169.0 4264.9 4424.3 4480.8 4539.3 3471.9 3537.2 3543.2 3592.9 3603.1 1092.5 1113.7 1126.3 1130.8 1133.1 908.2 932.8 933.5 939.2 953.1 5388.5 5608.3 5675.8 5816.4 5847.2 895.9 897.8 931.1 960.3 973.0 3789.3 3795.6 3861.8 3877.7 3919.9 9386.4 9461.0 9470.6 9471.0 9475.2 9334.8 9375.5 9387.5 9393.4 9404.0 7059.3 7343.8 7361.6 7377.9 7445.7 5214.9 5285.6 5366.4 5558.6 5888.0 8444.9 8471.7 8521.0 8542.1 8594.1 8602.2 8693.8 8697.2 8731.2 8783.6 6386.2 6545.4 6545.9 6864.9 7087.2 7132.6 7196.2 7371.8 7496.0 7566.2 6755.4 6922.2 6952.8 6986.5 7038.6 6606.5 6816.9 6822.6 6830.3 6857.5 6055.0 6055.2 6209.5 6268.2 6364.9 4678.7 4751.8 4754.8 4772.8 4849.2 4599.7 4725.9 4728.1 4815.6 4834.1 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:4563.76 Median:5180.25 stddev:1321.60 (25.5%) stddev-sans-first:1286.45 18:27:33 INFO - PROCESS | 1937 | Values: 325.4 333.3 335.9 340.6 343.8 5024.7 5157.3 5230.8 5279.7 5284.3 5019.9 5076.5 5099.8 5151.0 5203.1 4093.3 4109.7 4113.2 4123.4 4129.1 3888.9 3919.7 4110.7 4130.5 4149.9 4070.0 4095.9 4102.0 4107.5 4108.2 4099.3 4107.9 4110.1 4111.4 4122.2 3674.6 3677.6 3679.6 3681.0 3700.7 4945.8 4998.4 5010.8 5012.8 5055.8 4633.2 4654.7 4659.5 4682.4 4683.6 5300.2 5358.7 5371.0 5383.7 5395.4 3870.5 3956.4 3978.8 3985.5 3995.1 5501.5 5515.6 5527.8 5532.7 5546.4 4022.3 4025.6 4029.6 4039.9 4090.7 4048.3 4083.2 4099.5 4101.3 4140.6 4582.4 4622.5 4633.2 4652.8 4653.2 5383.7 5415.3 5427.2 5428.7 5449.5 1328.8 1364.4 1370.4 1387.8 1405.1 943.6 948.2 954.2 958.6 964.6 5056.0 5074.8 5077.2 5081.6 5098.1 5265.4 5335.7 5380.7 5399.9 5411.6 4858.4 4969.0 5026.5 5057.3 5058.3 2809.2 2819.6 2837.1 2839.8 2859.4 5508.6 5541.2 5545.8 5568.8 5581.5 5238.7 5419.6 5435.8 5448.2 5521.3 5441.8 5491.3 5510.6 5512.7 5550.8 5363.4 5366.6 5376.6 5396.9 5408.3 5297.4 5559.7 5580.0 5584.6 5598.3 5805.6 5821.9 5829.7 5841.4 5845.9 3986.7 4033.4 4034.2 4041.1 4058.8 5573.2 5576.4 5620.4 5647.4 5653.2 5519.2 5521.9 5554.2 5574.3 5605.2 5321.4 5478.2 5511.8 5518.4 5531.5 5431.9 5450.7 5451.9 5452.8 5460.1 5563.1 5595.5 5639.9 5640.5 5672.0 5301.5 5349.5 5405.3 5418.6 5440.3 18:27:33 INFO - PROCESS | 1937 | 18:27:33 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:18275.53 Median:26238.19 stddev:23660.23 (90.2%) stddev-sans-first:23689.63 18:27:33 INFO - PROCESS | 1937 | Values: 661.6 669.9 715.3 777.4 811.1 15068.1 16212.8 16241.5 16264.8 16271.6 14529.9 14651.4 14693.3 14702.7 14703.7 652.5 667.3 763.8 769.7 783.0 778.6 780.2 788.5 788.6 788.7 831.1 835.1 837.6 838.9 839.1 682.6 701.2 807.9 825.0 831.0 608.7 608.9 609.1 610.0 610.0 13995.2 13999.7 14027.5 14076.5 14279.4 14223.7 14235.5 14263.0 14298.4 14313.4 12521.9 12576.4 12764.7 13035.7 13125.5 4193.6 4226.6 4240.0 4301.1 4356.9 74652.6 75685.0 76093.2 76670.0 76909.0 11014.0 11028.2 11052.6 11229.7 11579.4 21003.0 21188.8 21226.9 21237.2 21269.5 7795.3 7840.8 7934.9 7999.5 8245.3 4449.2 4520.4 4594.5 4656.0 4668.5 3437.8 3518.5 3523.8 3526.0 3528.6 595.0 604.6 612.5 614.5 614.7 647.4 679.4 689.9 696.5 714.8 8720.0 9176.3 9221.9 9325.4 9543.3 626.7 681.0 694.0 699.9 711.0 4413.8 4699.8 4702.8 4713.1 4732.4 83647.6 84100.6 85562.1 85761.6 86472.0 73540.3 74352.0 74934.3 76306.0 76825.2 67794.0 69156.7 69205.7 70734.0 72159.6 8946.5 9006.5 9043.2 9103.9 9208.9 47047.5 47744.0 48588.4 48968.5 49834.8 52067.5 52152.2 54272.0 55751.7 56486.0 13398.3 13421.5 13433.9 13454.9 13615.9 14475.6 14590.4 15124.3 15561.0 15609.1 14209.3 14237.1 14531.2 14658.4 15028.5 12926.9 13939.7 14140.2 14304.0 14770.5 14274.1 14456.1 14534.6 14828.2 15009.6 11294.5 11437.5 11699.9 11839.9 12278.8 11455.0 11912.2 12038.5 12045.4 12410.6 18:27:33 INFO - PROCESS | 1937 | -------- Summary: end -------- 18:27:33 INFO - PROCESS | 1937 | 18:27:34 INFO - TEST-INFO | 1937: exit 0 18:27:34 INFO - TEST-OK | dromaeo_css | took 1118087ms 18:27:34 INFO - TEST-START | kraken 18:27:34 INFO - operating with platform_type : linux_ 18:27:34 INFO - Initialising browser for kraken test... 18:27:34 INFO - TEST-INFO | started process 2249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4xQOMY/profile http://localhost:43890/getInfo.html) 18:27:38 INFO - PROCESS | 2249 | __metrics Screen width/height:1600/1200 18:27:38 INFO - PROCESS | 2249 | colorDepth:24 18:27:38 INFO - PROCESS | 2249 | Browser inner width/height: 1024/697 18:27:38 INFO - PROCESS | 2249 | __metrics 18:27:39 INFO - PROCESS | 2249 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:27:39 INFO - PROCESS | 2249 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:27:39 INFO - PROCESS | 2249 | console.error: PushService: 18:27:39 INFO - PROCESS | 2249 | stateChangeProcessEnqueue: Error transitioning state 18:27:39 INFO - PROCESS | 2249 | UnknownError 18:27:40 INFO - TEST-INFO | 2249: exit 0 18:27:40 INFO - Browser initialized. 18:27:40 INFO - Running cycle 1/1 for kraken test... 18:27:40 INFO - TEST-INFO | started process 2332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4xQOMY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:52 INFO - PROCESS | 2332 | RSS: Main: 172634112 18:27:52 INFO - PROCESS | 2332 | 18:27:55 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43890/tests/kraken/driver.html?testName=audio-beat-detection) 18:27:56 INFO - PROCESS | 2332 | RSS: Main: 234864640 18:27:56 INFO - PROCESS | 2332 | 18:27:58 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43890/tests/kraken/driver.html?testName=audio-dft) 18:27:59 INFO - PROCESS | 2332 | RSS: Main: 217247744 18:27:59 INFO - PROCESS | 2332 | 18:28:02 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43890/tests/kraken/driver.html?testName=audio-fft) 18:28:03 INFO - PROCESS | 2332 | RSS: Main: 299094016 18:28:03 INFO - PROCESS | 2332 | 18:28:05 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43890/tests/kraken/driver.html?testName=audio-oscillator) 18:28:05 INFO - PROCESS | 2332 | RSS: Main: 208625664 18:28:05 INFO - PROCESS | 2332 | 18:28:07 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43890/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:28:08 INFO - PROCESS | 2332 | RSS: Main: 215003136 18:28:08 INFO - PROCESS | 2332 | 18:28:11 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43890/tests/kraken/driver.html?testName=imaging-darkroom) 18:28:12 INFO - PROCESS | 2332 | RSS: Main: 253558784 18:28:12 INFO - PROCESS | 2332 | 18:28:15 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43890/tests/kraken/driver.html?testName=imaging-desaturate) 18:28:16 INFO - PROCESS | 2332 | RSS: Main: 253382656 18:28:16 INFO - PROCESS | 2332 | 18:28:18 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43890/tests/kraken/driver.html?testName=json-parse-financial) 18:28:19 INFO - PROCESS | 2332 | RSS: Main: 252518400 18:28:19 INFO - PROCESS | 2332 | 18:28:21 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43890/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:28:21 INFO - PROCESS | 2332 | RSS: Main: 214237184 18:28:21 INFO - PROCESS | 2332 | 18:28:23 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:28:24 INFO - PROCESS | 2332 | RSS: Main: 222367744 18:28:24 INFO - PROCESS | 2332 | 18:28:26 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:28:26 INFO - PROCESS | 2332 | RSS: Main: 230785024 18:28:26 INFO - PROCESS | 2332 | 18:28:29 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:28:30 INFO - PROCESS | 2332 | RSS: Main: 239554560 18:28:30 INFO - PROCESS | 2332 | 18:28:32 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:28:33 INFO - PROCESS | 2332 | RSS: Main: 236601344 18:28:33 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | Cycle 1(1): loaded http://localhost:43890/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43890/tests/kraken/driver.html?testName=ai-astar) 18:28:35 INFO - PROCESS | 2332 | RSS: Main: 286625792 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | __start_tp_report 18:28:35 INFO - PROCESS | 2332 | _x_x_mozilla_page_load 18:28:35 INFO - PROCESS | 2332 | _x_x_mozilla_page_load_details 18:28:35 INFO - PROCESS | 2332 | |i|pagename|runs| 18:28:35 INFO - PROCESS | 2332 | |0;ai-astar;109;115;113;114;112;112;111;111;111;111 18:28:35 INFO - PROCESS | 2332 | |1;audio-beat-detection;155;127;123;168;129;127;130;124;143;123 18:28:35 INFO - PROCESS | 2332 | |2;audio-dft;161;162;163;161;161;161;161;159;160;160 18:28:35 INFO - PROCESS | 2332 | |3;audio-fft;76;78;76;108;76;76;77;76;91;76 18:28:35 INFO - PROCESS | 2332 | |4;audio-oscillator;85;85;114;84;87;84;94;84;84;108 18:28:35 INFO - PROCESS | 2332 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;118;116 18:28:35 INFO - PROCESS | 2332 | |6;imaging-darkroom;147;147;146;146;146;146;146;146;147;145 18:28:35 INFO - PROCESS | 2332 | |7;imaging-desaturate;95;96;95;95;95;95;96;95;95;95 18:28:35 INFO - PROCESS | 2332 | |8;json-parse-financial;55;55;54;55;55;56;55;55;55;55 18:28:35 INFO - PROCESS | 2332 | |9;json-stringify-tinderbox;62;62;61;62;62;62;62;63;62;63 18:28:35 INFO - PROCESS | 2332 | |10;stanford-crypto-aes;81;80;81;80;81;79;80;83;80;87 18:28:35 INFO - PROCESS | 2332 | |11;stanford-crypto-ccm;129;133;140;140;138;147;130;135;174;162 18:28:35 INFO - PROCESS | 2332 | |12;stanford-crypto-pbkdf2;168;168;166;163;167;166;171;171;173;167 18:28:35 INFO - PROCESS | 2332 | |13;stanford-crypto-sha256-iterative;68;65;67;67;65;65;68;66;66;67 18:28:35 INFO - PROCESS | 2332 | __end_tp_report 18:28:35 INFO - PROCESS | 2332 | __start_cc_report 18:28:35 INFO - PROCESS | 2332 | _x_x_mozilla_cycle_collect,1681 18:28:35 INFO - PROCESS | 2332 | __end_cc_report 18:28:35 INFO - PROCESS | 2332 | __startTimestamp1481250515541__endTimestamp 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | ------- Summary: start ------- 18:28:35 INFO - PROCESS | 2332 | Number of tests: 14 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:1.73 (1.5%) stddev-sans-first:1.48 18:28:35 INFO - PROCESS | 2332 | Values: 109.0 115.0 113.0 114.0 112.0 112.0 111.0 111.0 111.0 111.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:129.50 stddev:15.46 (11.9%) stddev-sans-first:14.59 18:28:35 INFO - PROCESS | 2332 | Values: 155.0 127.0 123.0 168.0 129.0 127.0 130.0 124.0 143.0 123.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 18:28:35 INFO - PROCESS | 2332 | Values: 161.0 162.0 163.0 161.0 161.0 161.0 161.0 159.0 160.0 160.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#3] audio-fft Cycles:10 Average:81.00 Median:76.00 stddev:10.56 (13.9%) stddev-sans-first:11.05 18:28:35 INFO - PROCESS | 2332 | Values: 76.0 78.0 76.0 108.0 76.0 76.0 77.0 76.0 91.0 76.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:84.50 stddev:11.11 (13.1%) stddev-sans-first:11.58 18:28:35 INFO - PROCESS | 2332 | Values: 85.0 85.0 114.0 84.0 87.0 84.0 94.0 84.0 84.0 108.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:28:35 INFO - PROCESS | 2332 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 118.0 116.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 18:28:35 INFO - PROCESS | 2332 | Values: 147.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:28:35 INFO - PROCESS | 2332 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 18:28:35 INFO - PROCESS | 2332 | Values: 55.0 55.0 54.0 55.0 55.0 56.0 55.0 55.0 55.0 55.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 18:28:35 INFO - PROCESS | 2332 | Values: 62.0 62.0 61.0 62.0 62.0 62.0 62.0 63.0 62.0 63.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.30 (2.8%) stddev-sans-first:2.44 18:28:35 INFO - PROCESS | 2332 | Values: 81.0 80.0 81.0 80.0 81.0 79.0 80.0 83.0 80.0 87.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:140.00 stddev:14.57 (10.4%) stddev-sans-first:14.57 18:28:35 INFO - PROCESS | 2332 | Values: 129.0 133.0 140.0 140.0 138.0 147.0 130.0 135.0 174.0 162.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:2.94 (1.8%) stddev-sans-first:3.12 18:28:35 INFO - PROCESS | 2332 | Values: 168.0 168.0 166.0 163.0 167.0 166.0 171.0 171.0 173.0 167.0 18:28:35 INFO - PROCESS | 2332 | 18:28:35 INFO - PROCESS | 2332 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.09 18:28:35 INFO - PROCESS | 2332 | Values: 68.0 65.0 67.0 67.0 65.0 65.0 68.0 66.0 66.0 67.0 18:28:35 INFO - PROCESS | 2332 | -------- Summary: end -------- 18:28:35 INFO - PROCESS | 2332 | 18:28:36 INFO - TEST-INFO | 2332: exit 0 18:28:36 INFO - TEST-OK | kraken | took 62144ms 18:28:36 INFO - SUITE-END | took 1180s 18:28:37 INFO - Completed test suite (00:19:40) 18:28:37 INFO - javascript benchmark 18:28:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3241.173500757305, 3255.300110476247, 3271.8854840080603, 3303.785253958494, 3317.9170520736984, 5271.604629652777, 5280.471952804718, 5369.114096174131, 5377.085895397782, 5381.488758567934, 5333.679979201251, 5343.465653434655, 5352.197170424438, 5358.544523715489, 5368.489993450626, 4858.902821943559, 4897.975510122445, 4906.435759887605, 4925.064237794808, 4961.900761984758, 4885.267209918523, 4940.678855874375, 4941.0, 4947.851564453073, 4949.579285760715, 4825.20384136617, 4858.441279252896, 4860.222364421693, 4870.4885986971385, 4930.359053323081, 4893.7063776173545, 4903.362562866841, 4909.459959404463, 4915.852524424237, 4956.033573453178, 4803.375561177025, 4803.519648035169, 4826.0347930414055, 4837.564619184255, 4865.756712164378, 5233.058049551079, 5257.605679574009, 5261.526462618344, 5271.947280527184, 5345.091334473149, 5616.943830561683, 5675.5459563234845, 5675.914861277084, 5680.091185410356, 5680.715964201815, 5571.359293681238, 5613.971930140364, 5694.629849059798, 5703.486686198221, 5747.396523365028, 5351.973240133813, 5374.381946076252, 5386.973065134649, 5428.484293992104, 5445.809396671098, 5822.679752613562, 5834.504067154255, 5835.9708201458725, 5842.182094506772, 5844.649321040752, 5520.199571062173, 5573.303337082864, 5580.49775520197, 5582.358028826737, 5636.85907852307, 5596.384397716248, 5614.719264036864, 5637.633553818989, 5649.745761440745, 5654.971725141348, 5612.410696876769, 5654.802081927113, 5659.377468478464, 5697.0, 5713.542916566665, 5654.236678048411, 5659.5755318351285, 5661.490465858093, 5665.943340566541, 5709.800156994485, 4337.349397590387, 4412.183746006999, 4414.271645178574, 4425.822967081345, 4429.047754732748, 4021.075152714892, 4056.229316429869, 4057.5130984282073, 4062.614051665087, 4065.6950728694874, 5430.049741295336, 5433.918491222635, 5471.452854714496, 5503.229547863302, 5509.338879334506, 5744.770209191669, 5750.0512415450985, 5752.050911599623, 5790.739416726257, 5803.042497987784, 5229.895402092013, 5268.341457317835, 5356.91964620531, 5389.730513474311, 5460.0, 4975.776090075955, 4998.775055122528, 5005.524475174852, 5029.874253143701, 5048.242763585492, 5946.494547963385, 5952.732127054206, 5955.166276721263, 5955.37468565803, 5957.523398128139, 5562.054450743302, 5584.218209450599, 5733.0540460823495, 5742.655440673573, 5746.454086861741, 5885.941140588539, 5902.940970590239, 5917.733701983507, 5956.315023772322, 6013.488853447506, 5599.356074051373, 5619.775208991595, 5628.8311350657905, 5630.155476678531, 5652.85867853307, 5609.186667933209, 5716.8570785729025, 5717.942820571741, 5718.742656580547, 5725.198472213977, 5552.250446189712, 5567.387587365468, 5605.691686957093, 5623.297087864017, 5694.629849059798, 5615.691136987505, 5617.32592088952, 5621.213030175697, 5623.2408624835125, 5624.521915637217, 5509.256250406143, 5547.0570003098765, 5553.6390134641115, 5574.219609254788, 5578.386377498391, 5498.890022199614, 5545.7781688732, 5547.528460080777, 5617.859553511195, 5620.213170156263, 5401.540869026177, 5438.510534051954, 5438.728063596725, 5445.237666726583, 5452.781888724565], "value": 5284.560777846264, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.2789289386525, 589.2840199158022, 607.7386723708803, 624.8437890527371, 629.6473974574242, 31161.532577011334, 31700.68299317009, 40881.97795055121, 41765.582344176546, 41983.370249446285, 39097.609023909754, 42596.36105458413, 42637.57362426375, 42661.786691066576, 42746.35880461796, 8384.455010424319, 8534.658613655478, 8544.829103417927, 8546.230839224469, 8552.35857310701, 7736.419768517384, 7908.44640875139, 7915.327197188246, 8097.635606397696, 8324.167583241657, 9940.850887236697, 10175.796484070313, 10175.898241017569, 10408.583656653762, 10416.218783591223, 10097.293189476739, 10162.39025658463, 10175.542100605491, 10177.592896284139, 10180.490975451272, 9868.753281167956, 9954.452505112216, 9956.452395118265, 9961.053699898497, 9964.90035099647, 39792.801035994926, 39955.40066898999, 40010.0, 40087.19825603501, 40089.59910400888, 3369.552191107739, 3486.1882581512837, 3530.6292839252023, 3581.8209089545417, 3742.7192960527796, 30288.70454613035, 30493.69506304953, 30769.230769230726, 31128.533072003713, 32164.6783532164, 4059.878203653895, 4118.629323360882, 4163.542010378856, 4306.5262821089655, 4348.152110338485, 321413.0, 322621.0, 324559.0, 326590.0, 328456.9999999976, 53134.0, 53551.99999999922, 53600.0, 53608.46391536113, 53866.73066634642, 56600.0, 57126.143107853415, 57142.142867857016, 57225.0, 57290.140647890315, 15820.288087036111, 16566.0, 16566.751498727528, 16567.337306507845, 16582.75125873113, 11241.55033798639, 11257.21199516026, 11275.210735248455, 11278.54885804575, 11355.716107097389, 13830.37763300654, 14153.433862645585, 14981.850181498046, 15423.151726654918, 15482.148481833607, 5722.284714410699, 5758.913616295759, 5762.711864406763, 5941.940580594225, 5944.286685597756, 940.1538615246196, 1016.7305663999047, 1051.2746205118444, 1112.0880877680224, 1130.796456637813, 30685.69314306873, 31269.96924363229, 31274.687253127177, 31678.366432671217, 31815.204619884687, 1078.8489611454402, 1103.5254840418697, 1109.7669489407315, 1131.4795194210756, 1178.3106882473678, 15865.365385384686, 16421.58946026335, 16474.58813529671, 16569.857535616095, 16796.748048779278, 133372.0, 134501.3274933619, 134510.0, 134755.32622336826, 135425.0, 55171.448285517436, 55215.72392138094, 55217.44782552203, 55306.446935530126, 55360.72319638456, 1305.3081866610712, 1363.1412210307442, 1374.6288502104576, 1388.5834249725042, 1389.4928351151757, 29301.85349073241, 30586.235344116576, 30833.229169270948, 33483.16292092717, 33885.0, 54048.729756350964, 54606.72696636491, 54685.0, 54722.0, 54804.327394165644, 409658.0, 415509.0, 415537.0, 416005.0, 417659.0, 24532.0, 25067.0, 26369.20892373232, 27218.591721124594, 27665.58501622437, 40785.59214407818, 44729.0, 45113.77443112763, 45228.321575177055, 45493.54506454893, 38304.80847595744, 38311.0, 39285.0, 39388.60611393849, 40440.19119617532, 38176.809115954245, 38391.6160838388, 38591.42112868365, 38765.0, 39363.409548856216, 38164.427533586466, 38814.4177837327, 39381.0, 39494.80252598719, 39614.207715846096, 32794.0, 34774.304513909075, 34990.12524686801, 35750.8212458936, 35781.0, 25963.0, 26414.603780942918, 26861.597076044265, 27413.31466713348, 27558.5866212011], "value": 18640.563758842705, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.3983466288602, 378.76138033039183, 396.4370593756865, 398.5257543523207, 415.31265755175195, 13923.582292531215, 14589.635259118528, 17614.91192544037, 18899.433017009495, 18905.905470472644, 15140.0, 17479.47561573155, 17879.374221902235, 18506.352277670285, 18524.722129168073, 5350.464953504647, 5558.249636299109, 5562.554995600342, 5570.359408668014, 5576.553875689955, 5254.710990895499, 5502.972485137569, 5522.116461366182, 5541.556675465973, 5541.833744987657, 5510.724463776815, 5510.9448905510835, 5512.035393806088, 5513.7794488220425, 5515.586331025169, 5315.707636080014, 5497.202905578689, 5520.447955204488, 5536.197251398545, 5536.446355364431, 4737.147313483571, 4980.103581355354, 4982.679589908677, 4983.302337672729, 4986.601071914238, 21383.35849924505, 21749.34751957444, 21838.0, 21896.12415503378, 21919.890400548054, 2276.146445083094, 2286.142696488817, 2416.359664688859, 2531.088808029108, 2816.9295767605768, 9728.854067188999, 10069.798604027952, 10386.844197337046, 13205.603831885059, 14403.351849166813, 1823.088455772114, 1872.4663470910725, 1880.0411789987068, 1922.73099754163, 1959.6276707425543, 73747.67169975827, 76607.23392766056, 78025.60987195028, 78723.60638196829, 79885.20114798835, 9185.77035574116, 9495.620175193053, 9620.807583848424, 9707.369021013612, 10008.649697260707, 12849.4217760201, 13247.0, 13296.534621288401, 13318.134321269086, 13327.600601936658, 7777.688892444239, 7790.610469476504, 7944.920550794418, 8036.67853285862, 8047.476914000571, 7881.487703299267, 7888.0, 7905.328047115944, 8087.878681819778, 8120.75637730869, 4025.718199725991, 4074.30736774749, 4095.119549296856, 4099.97950010248, 4169.082801783603, 1900.5248687828043, 1908.1985566062287, 1958.520162560182, 1992.3325685895097, 2026.7567891853073, 935.3125452792192, 943.0852073488705, 970.5341436110565, 978.8727465429448, 1003.2575893838507, 17479.0, 19034.428967131007, 19096.80903190978, 19198.13608387626, 19320.32378866761, 943.1464524605243, 944.4994153098869, 990.2672022703148, 1006.0442579549457, 1014.6702321745462, 8430.536320502431, 10432.73918152037, 10734.336177892439, 10747.946260268805, 10760.946195268974, 295463.0, 297704.0, 382431.0, 384093.0, 384582.0, 65413.15042194967, 75407.86888196682, 85559.57220213859, 88386.55806721054, 92119.0, 999.8150342186718, 1013.4983183324155, 1048.3552615141784, 1051.2037978975886, 1052.610534102387, 16613.335466581684, 17070.658586827947, 17538.561535961704, 17543.210555524776, 17746.46760597184, 38819.0, 39693.404598930465, 40302.798486007385, 40806.591934081465, 40824.79587602043, 54874.45125548853, 55958.0, 56510.0, 57484.42515574791, 58431.0, 7738.2261773822165, 7803.7268695595385, 8141.592920354077, 8187.672493100209, 8532.658693652433, 11817.350045747566, 12703.174293670882, 12890.228600850862, 12965.416556254802, 13446.058775885595, 11263.267887587608, 11313.660590182308, 11385.430728463709, 11668.183227174017, 11834.408279586158, 11017.338959662446, 11203.551857925591, 11262.7747445049, 11283.0, 11815.17293789461, 10711.500389945259, 11003.22977391575, 11286.774264514499, 11621.593244236412, 11858.703532411759, 8368.20502052304, 8477.915220847712, 8490.41042482049, 8642.308615310634, 8756.956215218883, 11021.283616564897, 11207.271527350696, 11463.942680286546, 11497.885021149681, 11693.0], "value": 8785.318011244104, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.0714303559018, 1403.4386245501798, 1450.209635748517, 1575.566719152233, 1730.9567260818478, 6900.2754710755335, 7751.224877512253, 7965.60171991401, 7965.999999999993, 8155.143709910463, 8013.439059265839, 8044.91955080449, 8049.034115906099, 8101.675932962689, 8105.108438071822, 1862.683343831545, 1917.0127384397047, 2081.09472379515, 2120.416885356524, 2126.1176611706123, 2083.0626218201796, 2151.139544182324, 2153.6984822124914, 2158.3956492182215, 2159.654455287154, 2127.6595744680853, 2136.5620047890147, 2164.5346250556127, 2166.2093335932427, 2169.783021697833, 2098.4124445155303, 2112.7464704235513, 2115.608612406694, 2116.1323857218467, 2118.5868755592664, 2075.4603803011173, 2120.968185477219, 2127.585120901425, 2127.595756806217, 2135.263334149716, 7830.960845195737, 7881.527108373516, 7899.605019748989, 7901.446898717092, 7903.051633803981, 4874.366332376804, 5035.697858128524, 5036.395632524121, 5043.293938848564, 5045.571126454256, 3054.5570892220503, 3217.887373941901, 3267.036224313833, 3270.934581308384, 3287.260366417561, 954.2699834626517, 1021.1881554164457, 1031.1493018259891, 1040.3861721584226, 1075.537518866887, 10632.362058276529, 10741.194410419172, 10968.890311096866, 11050.889491105147, 11164.0, 4194.2869712149, 4215.051613387025, 4234.9364759528635, 4238.237117318912, 4322.459692538433, 9560.139587437025, 9575.952120239353, 9588.66439674608, 9591.136797688241, 9598.232141428669, 4782.426108866958, 4854.490278520775, 4864.854054378375, 4903.166461701519, 4927.088488629615, 4168.979155104205, 4264.893377665521, 4424.269995450714, 4480.7535585542455, 4539.341795439643, 3471.864803146047, 3537.239493508908, 3543.2027793746715, 3592.9101772455374, 3603.117236277129, 1092.4537731134433, 1113.704868209925, 1126.267925848189, 1130.7908037013015, 1133.1161693878787, 908.2416182487565, 932.785459344341, 933.4539294512786, 939.2345238630494, 953.1278879825021, 5388.5419739321815, 5608.270924779753, 5675.829725108253, 5816.360200377955, 5847.239858818326, 895.9372843900865, 897.8294124116397, 931.1154403316876, 960.2701946520694, 972.9805403891883, 3789.2989796887664, 3795.582485926546, 3861.806909654506, 3877.65101140893, 3919.9020024499614, 9386.436813791195, 9460.95269523648, 9470.621175152917, 9471.0, 9475.241980641533, 9334.813303733887, 9375.5312234388, 9387.5306234688, 9393.389429687048, 9403.952980235055, 7059.2940705928995, 7343.760153153233, 7361.6319184040585, 7377.88933166003, 7445.739399121005, 5214.921776173436, 5285.550728187994, 5366.356037275552, 5558.610897237155, 5888.0, 8444.873326899979, 8471.745847624581, 8521.0, 8542.145785421408, 8594.097619750086, 8602.18279263469, 8693.826123477622, 8697.173768491983, 8731.214190722865, 8783.648654054023, 6386.201724784402, 6545.363659085228, 6545.887199176056, 6864.862702745817, 7087.184973728087, 7132.607706576188, 7196.208417074223, 7371.8157046074275, 7495.962520187364, 7566.167721550515, 6755.4257888080065, 6922.169339679271, 6952.791416257521, 6986.54587451814, 7038.577685338896, 6606.47148228131, 6816.931830681629, 6822.556533825285, 6830.282820303796, 6857.5199736018, 6054.969725151346, 6055.212822333068, 6209.472194863487, 6268.153799237045, 6364.93635063662, 4678.719276843401, 4751.7861696224445, 4754.786034628519, 4772.832950846704, 4849.224124140121, 4599.655025873006, 4725.905481890274, 4728.148933192058, 4815.638827087912, 4834.129856625841], "value": 4213.773626207456, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.4369940003795, 333.2718011145647, 335.9479280711489, 340.5810852651239, 343.78341644763793, 5024.723640199788, 5157.349648112606, 5230.790768369266, 5279.683219006857, 5284.2866213061225, 5019.924701129486, 5076.502431782624, 5099.827039780844, 5151.042603810556, 5203.141481655532, 4093.2836753568154, 4109.6506796922295, 4113.218488487193, 4123.381492776078, 4129.132882094764, 3888.863889763859, 3919.7060220483436, 4110.671146308303, 4130.545639979599, 4149.875503734893, 4069.9593004069875, 4095.897602559945, 4102.031025107935, 4107.507099148106, 4108.157827645326, 4099.3031184698675, 4107.938380924288, 4110.116324990141, 4111.403846442264, 4122.237386083569, 3674.5747806447885, 3677.6322367763278, 3679.6136405677285, 3680.9815950920342, 3700.703943684499, 4945.7774400152075, 4998.425181104183, 5010.849674509734, 5012.774425150877, 5055.823046193365, 4633.181491249097, 4654.650901182425, 4659.483878705495, 4682.391289132425, 4683.555062269079, 5300.178472336794, 5358.732063396854, 5371.0, 5383.650062745909, 5395.406505284415, 3870.4581358609817, 3956.358111383772, 3978.781167035812, 3985.501812273466, 3995.141044675409, 5501.477359650827, 5515.586331025189, 5527.778888844441, 5532.727472681306, 5546.4453554644615, 4022.255882661717, 4025.5571887092397, 4029.637332640018, 4039.8788036358956, 4090.6727461802984, 4048.2713111640187, 4083.224187404384, 4099.528554216244, 4101.343784994387, 4140.565240649748, 4582.381378513925, 4622.514635963243, 4633.235516139866, 4652.790624421909, 4653.162430762428, 5383.703896285742, 5415.296011518478, 5427.213054107132, 5428.67427954324, 5449.536789372948, 1328.7674656934998, 1364.413302280029, 1370.4038743146764, 1387.8265216847894, 1405.1358414574963, 943.5535285174805, 948.1656142594472, 954.1698722111669, 958.6309270930742, 964.647903515226, 5055.974720126376, 5074.847754567369, 5077.1876499759865, 5081.618878584121, 5098.107831129538, 5265.420803711589, 5335.706536140549, 5380.650257733313, 5399.946000540023, 5411.563657077705, 4858.416989961227, 4968.950310496921, 5026.547610715053, 5057.342545469139, 5058.342415485963, 2809.2415047936943, 2819.6052552642645, 2837.1274958527683, 2839.829610223393, 2859.3566447549133, 5508.614396992252, 5541.168824676251, 5545.7781688732, 5568.832935011957, 5581.469760372758, 5238.68567885928, 5419.620626556182, 5435.7825686972865, 5448.237246785453, 5521.282233309644, 5441.782328706887, 5491.313585801775, 5510.586705997066, 5512.6692398456225, 5550.7779688812, 5363.383210930637, 5366.597505187048, 5376.623636345418, 5396.919046214389, 5408.296921400351, 5297.417284098669, 5559.694216818114, 5580.0, 5584.609077364708, 5598.3282006159525, 5805.564582656233, 5821.883562328645, 5829.708514574169, 5841.386654401226, 5845.88928103659, 3986.661133803659, 4033.3546632538655, 4034.23349563582, 4041.090754580196, 4058.7970601470397, 5573.191887176417, 5576.358718747396, 5620.353659329229, 5647.3505546861, 5653.208550802973, 5519.172124181405, 5521.861953451196, 5554.167124848709, 5574.275344205227, 5605.215269862141, 5321.36143662763, 5478.150886612581, 5511.751971161227, 5518.392976772471, 5531.529819965348, 5431.918521222264, 5450.691833959901, 5451.890962180813, 5452.7546260417585, 5460.099083651312, 5563.13771365439, 5595.496405323541, 5639.943600563942, 5640.520555752807, 5671.971640141773, 5301.5493683037375, 5349.518543331119, 5405.297311349499, 5418.647787893774, 5440.265564148947], "value": 4145.718569025811, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.6294874870073, 669.9162604674415, 715.2918610575532, 777.4480119115424, 811.1158836867813, 15068.09591424514, 16212.75680864788, 16241.51275461738, 16264.83735162648, 16271.59321016975, 14529.854701452983, 14651.413943442276, 14693.265336733173, 14702.705945881102, 14703.705925881475, 652.4845372155991, 667.2960027171332, 763.8243204063069, 769.6805825582377, 783.0173132718459, 778.6418247606108, 780.2002946979335, 788.5426452657474, 788.6411682684364, 788.6687591211705, 831.0941074229089, 835.0522157351389, 837.6481877611387, 838.932885369172, 839.1231163434226, 682.6450245872144, 701.1691145992016, 807.9151689072621, 824.9587520623945, 830.9584520773967, 608.6986941464004, 608.9025755879038, 609.0863704443335, 610.042233693102, 610.0452791381474, 13995.160290382508, 13999.720005599933, 14027.509037183752, 14076.507322243775, 14279.357428915724, 14223.715525689428, 14235.501757438544, 14263.0, 14298.428062877472, 14313.42746290147, 12521.93739031299, 12576.434060467302, 12764.680882977907, 13035.674108147277, 13125.54060607874, 4193.593149109946, 4226.619604235603, 4239.978800106011, 4301.075268817188, 4356.89107772305, 74652.62673686596, 75685.0, 76093.23906760973, 76670.0, 76909.0, 11014.0, 11028.172887033505, 11052.557897684163, 11229.66311010671, 11579.421028948518, 21003.0, 21188.78811211899, 21226.893865530572, 21237.150513979577, 21269.46826329354, 7795.337396321376, 7840.764777056697, 7934.920650793533, 7999.520028798291, 8245.340372770164, 4449.221386257456, 4520.367148599199, 4594.47163576193, 4656.0, 4668.486466488684, 3437.8281085945605, 3518.5425894634045, 3523.7709548879243, 3525.959841846661, 3528.576570811519, 594.9736704185245, 604.5888795619021, 612.5252928979987, 614.4746241963057, 614.7418084404561, 647.3688154049764, 679.4394624434861, 689.8670816441836, 696.4915611603558, 714.7998560403096, 8720.040795512488, 9176.311776616647, 9221.907780922105, 9325.393849399767, 9543.33196676226, 626.6709977261883, 680.993190068093, 693.954892931968, 699.9160100787938, 710.982225444368, 4413.8234470621455, 4699.812007519661, 4702.835400760957, 4713.104510143062, 4732.384789977281, 83647.5817620908, 84100.57949710212, 85562.14437855667, 85761.57119214488, 86472.0, 73540.33665118528, 74352.0, 74934.25065749382, 76306.0, 76825.23174768292, 67793.98309025369, 69156.6542167286, 69205.65397172981, 70734.0, 72159.63920180366, 8946.507942063248, 9006.459612423276, 9043.18611324984, 9103.863442048241, 9208.907910920805, 47047.529524705686, 47744.0, 48588.38410351011, 48968.5103148964, 49834.75082624564, 52067.47932520627, 52152.21771673352, 54272.0, 55751.721241393534, 56486.0, 13398.26309552984, 13421.463141474233, 13433.932830335785, 13454.932725336703, 13615.93192034073, 14475.638109047359, 14590.416383344547, 15124.319405626993, 15560.995999283346, 15609.063456192665, 14209.289535523389, 14237.145771253758, 14531.200783956983, 14658.413663453342, 15028.549143525712, 12926.870731292567, 13939.651508712363, 14140.20431282734, 14304.0, 14770.48303309379, 14274.14355138695, 14456.13263204211, 14534.563963081124, 14828.184449855362, 15009.624759381102, 11294.491747871201, 11437.485313161094, 11699.941500292785, 11839.940800295943, 12278.75442491163, 11455.02169435936, 11912.225705329125, 12038.458269377725, 12045.374578144309, 12410.5656302029], "value": 7275.981273166719, "unit": "score"}], "name": "dromaeo_css", "value": 6922.117996514061, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 115.0, 113.0, 114.0, 112.0, 112.0, 111.0, 111.0, 111.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 127.0, 123.0, 168.0, 129.0, 127.0, 130.0, 124.0, 143.0, 123.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 161.0, 161.0, 161.0, 161.0, 159.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 76.0, 108.0, 76.0, 76.0, 77.0, 76.0, 91.0, 76.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 114.0, 84.0, 87.0, 84.0, 94.0, 84.0, 84.0, 108.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 55.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 80.0, 81.0, 79.0, 80.0, 83.0, 80.0, 87.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 133.0, 140.0, 140.0, 138.0, 147.0, 130.0, 135.0, 174.0, 162.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 166.0, 163.0, 167.0, 166.0, 171.0, 171.0, 173.0, 167.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 67.0, 67.0, 65.0, 65.0, 68.0, 66.0, 66.0, 67.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1513.4, "alertThreshold": 2.0}]} 18:28:37 INFO - Return code: 0 18:28:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:28:37 INFO - # TBPL SUCCESS # 18:28:37 INFO - Running post-action listener: _resource_record_post_action 18:28:37 INFO - [mozharness: 2016-12-09 02:28:37.331718Z] Finished run-tests step (success) 18:28:37 INFO - Running post-run listener: _resource_record_post_run 18:28:37 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 303423488; Read time: 16; Write time: 164540 18:28:37 INFO - TinderboxPrint: CPU usage
13.1% 18:28:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:28:37 INFO - TinderboxPrint: I/O write bytes / time
303,423,488 / 164,540 18:28:37 INFO - TinderboxPrint: CPU idle
8,304.9 (86.9%) 18:28:37 INFO - TinderboxPrint: CPU user
1,204.1 (12.6%) 18:28:37 INFO - TinderboxPrint: Swap in / out
0 / 0 18:28:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 15814656; Read time: 0; Write time: 9060 18:28:37 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 287608832; Read time: 0; Write time: 155480 18:28:37 INFO - Running post-run listener: _upload_blobber_files 18:28:37 INFO - Blob upload gear active. 18:28:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:28:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:28:37 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'] 18:28:37 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 18:28:38 INFO - (blobuploader) - INFO - Open directory for files ... 18:28:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:28:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:28:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:39 INFO - (blobuploader) - INFO - Done attempting. 18:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:28:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:43 INFO - (blobuploader) - INFO - Done attempting. 18:28:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:28:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:28:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:44 INFO - (blobuploader) - INFO - Done attempting. 18:28:44 INFO - (blobuploader) - INFO - Iteration through files over. 18:28:44 INFO - Return code: 0 18:28:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:28:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:28:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c1ffc3e8fbf746f169d2f52adf90062f54257577a793bb79d3e0330aacea27a9432afee3c9afb13c251db5aca80fd00566558267f18a620de51c64d0d895112", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ecc6c7039186f799fd85f7426ffd9aedbb3723bbcba308a51913e0cae0bdff0f378347d30ac2f6c210220f36526c36a1c4478e5660db4e3664036e5123d4c01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2daadeef1d8a30b9a311d2231fa0d5e8402d19b73b60a6eed4f6ac80a4974aa837f025d9be517d985027d1a04383dcbdae2049fa484ff413605f9334e99f12e8"} 18:28:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:28:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:28:44 INFO - Contents: 18:28:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c1ffc3e8fbf746f169d2f52adf90062f54257577a793bb79d3e0330aacea27a9432afee3c9afb13c251db5aca80fd00566558267f18a620de51c64d0d895112", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ecc6c7039186f799fd85f7426ffd9aedbb3723bbcba308a51913e0cae0bdff0f378347d30ac2f6c210220f36526c36a1c4478e5660db4e3664036e5123d4c01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2daadeef1d8a30b9a311d2231fa0d5e8402d19b73b60a6eed4f6ac80a4974aa837f025d9be517d985027d1a04383dcbdae2049fa484ff413605f9334e99f12e8"} 18:28:44 INFO - Running post-run listener: copy_logs_to_upload_dir 18:28:44 INFO - Copying logs to upload dir... 18:28:44 INFO - mkdir: /builds/slave/test/build/upload/logs 18:28:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.898050 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-08 18:28:44.091577) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 18:28:44.095042) ========= 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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 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/2c1ffc3e8fbf746f169d2f52adf90062f54257577a793bb79d3e0330aacea27a9432afee3c9afb13c251db5aca80fd00566558267f18a620de51c64d0d895112", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ecc6c7039186f799fd85f7426ffd9aedbb3723bbcba308a51913e0cae0bdff0f378347d30ac2f6c210220f36526c36a1c4478e5660db4e3664036e5123d4c01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2daadeef1d8a30b9a311d2231fa0d5e8402d19b73b60a6eed4f6ac80a4974aa837f025d9be517d985027d1a04383dcbdae2049fa484ff413605f9334e99f12e8"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011347 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1481240094/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2c1ffc3e8fbf746f169d2f52adf90062f54257577a793bb79d3e0330aacea27a9432afee3c9afb13c251db5aca80fd00566558267f18a620de51c64d0d895112", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ecc6c7039186f799fd85f7426ffd9aedbb3723bbcba308a51913e0cae0bdff0f378347d30ac2f6c210220f36526c36a1c4478e5660db4e3664036e5123d4c01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2daadeef1d8a30b9a311d2231fa0d5e8402d19b73b60a6eed4f6ac80a4974aa837f025d9be517d985027d1a04383dcbdae2049fa484ff413605f9334e99f12e8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-08 18:28:44.120410) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:28:44.120756) ========= 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=00204705a9daa57ed339a427000001b9-1481249245.857860-1505764690 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-08 18:28:44.203553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 18:28:44.203860) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-08 18:28:44.204169) ========= ========= Total master_lag: 0.17 =========