builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1483773967.38 results: success (0) buildid: 20170106232540 builduid: ead4a43db5ab434e81a1456ce219bf70 revision: 6f417be4dd3d5c77201c9b141687cccad6fc504d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.376851) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.377413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.377845) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.401195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.401544) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-06 23:26:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-01-06 23:26:07 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090694 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.515286) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.515628) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023306 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:07.576295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 23:26:07.576713) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 6f417be4dd3d5c77201c9b141687cccad6fc504d --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 6f417be4dd3d5c77201c9b141687cccad6fc504d --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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-06 23:26:08,058 truncating revision to first 12 chars 2017-01-06 23:26:08,058 Setting DEBUG logging. 2017-01-06 23:26:08,058 attempt 1/10 2017-01-06 23:26:08,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/6f417be4dd3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-06 23:26:08,771 unpacking tar archive at: mozilla-inbound-6f417be4dd3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.004008 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-06 23:26:08.595521) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:08.595879) ========= script_repo_revision: 6f417be4dd3d5c77201c9b141687cccad6fc504d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:08.596347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:08.596669) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-06 23:26:08.607623) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-01-06 23:26:08.608015) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:26:09 INFO - MultiFileLogger online at 20170106 23:26:09 in /builds/slave/test 23:26:09 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 23:26:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:26:09 INFO - {'append_to_log': False, 23:26:09 INFO - 'base_work_dir': '/builds/slave/test', 23:26:09 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 23:26:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:26:09 INFO - 'branch': 'Mozilla-Inbound', 23:26:09 INFO - 'buildbot_json_path': 'buildprops.json', 23:26:09 INFO - 'config_files': ('talos/linux_config.py',), 23:26:09 INFO - 'default_actions': ('clobber', 23:26:09 INFO - 'read-buildbot-config', 23:26:09 INFO - 'download-and-extract', 23:26:09 INFO - 'populate-webroot', 23:26:09 INFO - 'create-virtualenv', 23:26:09 INFO - 'install', 23:26:09 INFO - 'run-tests'), 23:26:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:26:09 INFO - 'download_minidump_stackwalk': True, 23:26:09 INFO - 'download_symbols': 'ondemand', 23:26:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:26:09 INFO - 'tooltool.py': '/tools/tooltool.py', 23:26:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:26:09 INFO - '/tools/misc-python/virtualenv.py')}, 23:26:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:26:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:26:09 INFO - 'installer_path': 'installer.exe', 23:26:09 INFO - 'log_level': 'info', 23:26:09 INFO - 'log_name': 'talos', 23:26:09 INFO - 'log_to_console': True, 23:26:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:26:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:26:09 INFO - 'opt_config_files': (), 23:26:09 INFO - 'pip_index': False, 23:26:09 INFO - 'sps_profile': False, 23:26:09 INFO - 'sps_profile_interval': 0, 23:26:09 INFO - 'suite': 'dromaeojs', 23:26:09 INFO - 'system_bits': '32', 23:26:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:26:09 INFO - 'title': 'talos-linux64-ix-037', 23:26:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:26:09 INFO - 'use_talos_json': True, 23:26:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:26:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:26:09 INFO - 'work_dir': 'build'} 23:26:09 INFO - [mozharness: 2017-01-07 07:26:09.188639Z] Running clobber step. 23:26:09 INFO - Running pre-action listener: _resource_record_pre_action 23:26:09 INFO - Running main action method: clobber 23:26:09 INFO - rmtree: /builds/slave/test/build 23:26:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:26:09 INFO - Running post-action listener: _resource_record_post_action 23:26:09 INFO - [mozharness: 2017-01-07 07:26:09.641521Z] Finished clobber step (success) 23:26:09 INFO - [mozharness: 2017-01-07 07:26:09.641622Z] Running read-buildbot-config step. 23:26:09 INFO - Running pre-action listener: _resource_record_pre_action 23:26:09 INFO - Running main action method: read_buildbot_config 23:26:09 INFO - Using buildbot properties: 23:26:09 INFO - { 23:26:09 INFO - "project": "", 23:26:09 INFO - "product": "firefox", 23:26:09 INFO - "script_repo_revision": "production", 23:26:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 23:26:09 INFO - "repository": "", 23:26:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 23:26:09 INFO - "buildid": "20170106232540", 23:26:09 INFO - "stage_platform": "linux64-pgo", 23:26:09 INFO - "basedir": "/builds/slave/test", 23:26:09 INFO - "buildnumber": 3206, 23:26:09 INFO - "platform": "ubuntu64_hw", 23:26:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:26:09 INFO - "slavebuilddir": "test", 23:26:09 INFO - "branch": "mozilla-inbound", 23:26:09 INFO - "revision": "6f417be4dd3d5c77201c9b141687cccad6fc504d", 23:26:09 INFO - "slavename": "talos-linux64-ix-037", 23:26:09 INFO - "builduid": "ead4a43db5ab434e81a1456ce219bf70", 23:26:09 INFO - "repo_path": "integration/mozilla-inbound" 23:26:09 INFO - } 23:26:09 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:26:09 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 23:26:09 INFO - Running post-action listener: _resource_record_post_action 23:26:09 INFO - [mozharness: 2017-01-07 07:26:09.643991Z] Finished read-buildbot-config step (success) 23:26:09 INFO - [mozharness: 2017-01-07 07:26:09.644081Z] Running download-and-extract step. 23:26:09 INFO - Running pre-action listener: _resource_record_pre_action 23:26:09 INFO - Running main action method: download_and_extract 23:26:09 INFO - mkdir: /builds/slave/test/build/tests 23:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:26:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/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 23:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/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 23:26:09 INFO - Downloaded 1361 bytes. 23:26:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:26:09 INFO - Using the following test package requirements: 23:26:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:26:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:26:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:26:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'jsshell-linux-x86_64.zip'], 23:26:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:26:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:26:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:26:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:26:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:26:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:26:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:26:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:26:09 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:26:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:26:09 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:26:09 INFO - Expected file size: 21841103 23:26:09 INFO - Obtained file size: 21841103 23:26:14 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 23:26:14 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:26:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:26:14 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:26:14 INFO - Expected file size: 21841103 23:26:14 INFO - Obtained file size: 21841103 23:26:18 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:26:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:26:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:26:19 INFO - Expected file size: 14403730 23:26:19 INFO - Obtained file size: 14403730 23:26: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')]} 23:26:19 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:26:19 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/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 23:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/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 23:26:21 INFO - Downloaded 61467445 bytes. 23:26:21 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:26:21 INFO - mkdir: /builds/slave/test/properties 23:26:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:26:21 INFO - Writing to file /builds/slave/test/properties/build_url 23:26:21 INFO - Contents: 23:26:21 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:26:22 INFO - Running post-action listener: _resource_record_post_action 23:26:22 INFO - Running post-action listener: set_extra_try_arguments 23:26:22 INFO - [mozharness: 2017-01-07 07:26:22.106302Z] Finished download-and-extract step (success) 23:26:22 INFO - [mozharness: 2017-01-07 07:26:22.106531Z] Running populate-webroot step. 23:26:22 INFO - Running pre-action listener: _resource_record_pre_action 23:26:22 INFO - Running main action method: populate_webroot 23:26:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:26:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:26:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:26:22 INFO - '--tppagecycles', 23:26:22 INFO - '7'], 23:26:22 INFO - 'tests': ['tsvgm']}}, 23:26:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:26:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:26:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:26:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:26:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['damp', 'tps']}, 23:26:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'tests': ['damp', 'tps']}, 23:26:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['dromaeo_dom']}, 23:26:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:26:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['basic_compositor_video']}, 23:26:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:26:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'other-e10s': {'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['a11yr', 23:26:22 INFO - 'ts_paint', 23:26:22 INFO - 'tpaint', 23:26:22 INFO - 'sessionrestore', 23:26:22 INFO - 'sessionrestore_no_auto_restore', 23:26:22 INFO - 'tabpaint']}, 23:26:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['tsvgx', 23:26:22 INFO - 'tsvgr_opacity', 23:26:22 INFO - 'tart', 23:26:22 INFO - 'tscrollx', 23:26:22 INFO - 'cart', 23:26:22 INFO - 'tsvg_static']}, 23:26:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:26:22 INFO - 'tsvgr_opacity', 23:26:22 INFO - 'tart', 23:26:22 INFO - 'tscrollx', 23:26:22 INFO - 'cart', 23:26:22 INFO - 'tsvg_static']}, 23:26:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'talos_options': ['--disable-e10s'], 23:26:22 INFO - 'tests': ['tp5o']}, 23:26:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:26:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'talos_options': ['--disable-e10s', 23:26:22 INFO - '--xperf_path', 23:26:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:22 INFO - 'tests': ['tp5n']}, 23:26:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:26:22 INFO - 'talos_options': ['--xperf_path', 23:26:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:22 INFO - 'tests': ['tp5n']}}, 23:26:22 INFO - 'talos.zip': {'path': '', 23:26:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:26:22 INFO - Running post-action listener: _resource_record_post_action 23:26:22 INFO - [mozharness: 2017-01-07 07:26:22.128947Z] Finished populate-webroot step (success) 23:26:22 INFO - [mozharness: 2017-01-07 07:26:22.129059Z] Running create-virtualenv step. 23:26:22 INFO - Running pre-action listener: _resource_record_pre_action 23:26:22 INFO - Running main action method: create_virtualenv 23:26:22 INFO - Creating virtualenv /builds/slave/test/build/venv 23:26:22 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 23:26:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:26:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:26:22 INFO - Using real prefix '/usr' 23:26:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:26:22 INFO - Installing distribute.............................................................................................................................................................................................done. 23:26:24 INFO - Installing pip.................done. 23:26:24 INFO - Return code: 0 23:26:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:26:24 INFO - Reading from file tmpfile_stdout 23:26:24 INFO - Output received: 23:26:24 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) 23:26:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:26: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')]} 23:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26: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')]} 23:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26: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', 'pip>=1.5'] in /builds/slave/test/build 23:26: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 pip>=1.5 23:26:24 INFO - Using env: {'DISPLAY': ':0', 23:26:24 INFO - 'HOME': '/home/cltbld', 23:26:24 INFO - 'LANG': 'en_US.UTF-8', 23:26:24 INFO - 'LANGUAGE': 'en_US:en', 23:26:24 INFO - 'LOGNAME': 'cltbld', 23:26:24 INFO - 'MAIL': '/var/mail/cltbld', 23:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:24 INFO - 'MOZ_NO_REMOTE': '1', 23:26:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:24 INFO - 'NO_EM_RESTART': '1', 23:26:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:24 INFO - 'PWD': '/builds/slave/test', 23:26:24 INFO - 'SHELL': '/bin/bash', 23:26:24 INFO - 'SHLVL': '1', 23:26:24 INFO - 'TERM': 'linux', 23:26:24 INFO - 'TMOUT': '86400', 23:26:24 INFO - 'USER': 'cltbld', 23:26:24 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:24 INFO - '_': '/tools/buildbot/bin/python'} 23:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:25 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 23:26:25 INFO - Cleaning up... 23:26:25 INFO - Return code: 0 23:26:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:25 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:25 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 23:26:25 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 23:26:25 INFO - Using env: {'DISPLAY': ':0', 23:26:25 INFO - 'HOME': '/home/cltbld', 23:26:25 INFO - 'LANG': 'en_US.UTF-8', 23:26:25 INFO - 'LANGUAGE': 'en_US:en', 23:26:25 INFO - 'LOGNAME': 'cltbld', 23:26:25 INFO - 'MAIL': '/var/mail/cltbld', 23:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:25 INFO - 'MOZ_NO_REMOTE': '1', 23:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:25 INFO - 'NO_EM_RESTART': '1', 23:26:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:25 INFO - 'PWD': '/builds/slave/test', 23:26:25 INFO - 'SHELL': '/bin/bash', 23:26:25 INFO - 'SHLVL': '1', 23:26:25 INFO - 'TERM': 'linux', 23:26:25 INFO - 'TMOUT': '86400', 23:26:25 INFO - 'USER': 'cltbld', 23:26:25 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:25 INFO - '_': '/tools/buildbot/bin/python'} 23:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:25 INFO - Downloading/unpacking psutil>=3.1.1 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:26:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:26:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:26:27 INFO - Installing collected packages: psutil 23:26:27 INFO - Running setup.py install for psutil 23:26:27 INFO - building 'psutil._psutil_linux' extension 23:26:27 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 23:26:27 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 23:26:27 INFO - building 'psutil._psutil_posix' extension 23:26:27 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 23:26:27 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 23:26:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:26:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:26:27 INFO - Successfully installed psutil 23:26:27 INFO - Cleaning up... 23:26:27 INFO - Return code: 0 23:26:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:26: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')]} 23:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26: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')]} 23:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:28 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:28 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 23:26:28 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 23:26:28 INFO - Using env: {'DISPLAY': ':0', 23:26:28 INFO - 'HOME': '/home/cltbld', 23:26:28 INFO - 'LANG': 'en_US.UTF-8', 23:26:28 INFO - 'LANGUAGE': 'en_US:en', 23:26:28 INFO - 'LOGNAME': 'cltbld', 23:26:28 INFO - 'MAIL': '/var/mail/cltbld', 23:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:28 INFO - 'MOZ_NO_REMOTE': '1', 23:26:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:28 INFO - 'NO_EM_RESTART': '1', 23:26:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:28 INFO - 'PWD': '/builds/slave/test', 23:26:28 INFO - 'SHELL': '/bin/bash', 23:26:28 INFO - 'SHLVL': '1', 23:26:28 INFO - 'TERM': 'linux', 23:26:28 INFO - 'TMOUT': '86400', 23:26:28 INFO - 'USER': 'cltbld', 23:26:28 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:28 INFO - '_': '/tools/buildbot/bin/python'} 23:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:26:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:26:30 INFO - Installing collected packages: mozsystemmonitor 23:26:30 INFO - Running setup.py install for mozsystemmonitor 23:26:30 INFO - Successfully installed mozsystemmonitor 23:26:30 INFO - Cleaning up... 23:26:30 INFO - Return code: 0 23:26:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:26:30 INFO - Using env: {'DISPLAY': ':0', 23:26:30 INFO - 'HOME': '/home/cltbld', 23:26:30 INFO - 'LANG': 'en_US.UTF-8', 23:26:30 INFO - 'LANGUAGE': 'en_US:en', 23:26:30 INFO - 'LOGNAME': 'cltbld', 23:26:30 INFO - 'MAIL': '/var/mail/cltbld', 23:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:30 INFO - 'MOZ_NO_REMOTE': '1', 23:26:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:30 INFO - 'NO_EM_RESTART': '1', 23:26:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:30 INFO - 'PWD': '/builds/slave/test', 23:26:30 INFO - 'SHELL': '/bin/bash', 23:26:30 INFO - 'SHLVL': '1', 23:26:30 INFO - 'TERM': 'linux', 23:26:30 INFO - 'TMOUT': '86400', 23:26:30 INFO - 'USER': 'cltbld', 23:26:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:30 INFO - '_': '/tools/buildbot/bin/python'} 23:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:30 INFO - Downloading/unpacking jsonschema==2.5.1 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:26:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:32 INFO - Downloading functools32-3.2.3-2.tar.gz 23:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:26:32 INFO - warning: no files found matching '*.txt' 23:26:32 INFO - no previously-included directories found matching 'build' 23:26:32 INFO - no previously-included directories found matching 'dist' 23:26:32 INFO - no previously-included directories found matching '.git*' 23:26:32 INFO - Installing collected packages: jsonschema, functools32 23:26:32 INFO - Running setup.py install for functools32 23:26:33 INFO - warning: no files found matching '*.txt' 23:26:33 INFO - no previously-included directories found matching 'build' 23:26:33 INFO - no previously-included directories found matching 'dist' 23:26:33 INFO - no previously-included directories found matching '.git*' 23:26:33 INFO - Successfully installed jsonschema functools32 23:26:33 INFO - Cleaning up... 23:26:33 INFO - Return code: 0 23:26:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:26:33 INFO - Using env: {'DISPLAY': ':0', 23:26:33 INFO - 'HOME': '/home/cltbld', 23:26:33 INFO - 'LANG': 'en_US.UTF-8', 23:26:33 INFO - 'LANGUAGE': 'en_US:en', 23:26:33 INFO - 'LOGNAME': 'cltbld', 23:26:33 INFO - 'MAIL': '/var/mail/cltbld', 23:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:33 INFO - 'MOZ_NO_REMOTE': '1', 23:26:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:33 INFO - 'NO_EM_RESTART': '1', 23:26:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:33 INFO - 'PWD': '/builds/slave/test', 23:26:33 INFO - 'SHELL': '/bin/bash', 23:26:33 INFO - 'SHLVL': '1', 23:26:33 INFO - 'TERM': 'linux', 23:26:33 INFO - 'TMOUT': '86400', 23:26:33 INFO - 'USER': 'cltbld', 23:26:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:33 INFO - '_': '/tools/buildbot/bin/python'} 23:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:26:33 INFO - Cleaning up... 23:26:33 INFO - Return code: 0 23:26:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:26:33 INFO - Using env: {'DISPLAY': ':0', 23:26:33 INFO - 'HOME': '/home/cltbld', 23:26:33 INFO - 'LANG': 'en_US.UTF-8', 23:26:33 INFO - 'LANGUAGE': 'en_US:en', 23:26:33 INFO - 'LOGNAME': 'cltbld', 23:26:33 INFO - 'MAIL': '/var/mail/cltbld', 23:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:33 INFO - 'MOZ_NO_REMOTE': '1', 23:26:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:33 INFO - 'NO_EM_RESTART': '1', 23:26:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:33 INFO - 'PWD': '/builds/slave/test', 23:26:33 INFO - 'SHELL': '/bin/bash', 23:26:33 INFO - 'SHLVL': '1', 23:26:33 INFO - 'TERM': 'linux', 23:26:33 INFO - 'TMOUT': '86400', 23:26:33 INFO - 'USER': 'cltbld', 23:26:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:33 INFO - '_': '/tools/buildbot/bin/python'} 23:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:33 INFO - Downloading/unpacking blobuploader==1.2.4 23:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:35 INFO - Downloading blobuploader-1.2.4.tar.gz 23:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:26:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:26:35 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 23:26:35 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 23:26:35 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 23:26:35 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 23:26:35 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 23:26:35 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 23:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:26:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26: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 23:26:36 INFO - Downloading docopt-0.6.1.tar.gz 23:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:26:36 INFO - Installing collected packages: blobuploader, requests, docopt 23:26:36 INFO - Running setup.py install for blobuploader 23:26:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:26:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:26:36 INFO - Running setup.py install for requests 23:26:36 INFO - Running setup.py install for docopt 23:26:36 INFO - Successfully installed blobuploader requests docopt 23:26:36 INFO - Cleaning up... 23:26:36 INFO - Return code: 0 23:26:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:36 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:36 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 23:26:36 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 23:26:36 INFO - Using env: {'DISPLAY': ':0', 23:26:36 INFO - 'HOME': '/home/cltbld', 23:26:36 INFO - 'LANG': 'en_US.UTF-8', 23:26:36 INFO - 'LANGUAGE': 'en_US:en', 23:26:36 INFO - 'LOGNAME': 'cltbld', 23:26:36 INFO - 'MAIL': '/var/mail/cltbld', 23:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:36 INFO - 'MOZ_NO_REMOTE': '1', 23:26:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:36 INFO - 'NO_EM_RESTART': '1', 23:26:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:36 INFO - 'PWD': '/builds/slave/test', 23:26:36 INFO - 'SHELL': '/bin/bash', 23:26:36 INFO - 'SHLVL': '1', 23:26:36 INFO - 'TERM': 'linux', 23:26:36 INFO - 'TMOUT': '86400', 23:26:36 INFO - 'USER': 'cltbld', 23:26:36 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:36 INFO - '_': '/tools/buildbot/bin/python'} 23:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:26:37 INFO - Running setup.py (path:/tmp/pip-y3Fbeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:26:37 INFO - Running setup.py (path:/tmp/pip-NckPW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:26:37 INFO - Running setup.py (path:/tmp/pip-l3Bx5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:26:37 INFO - Running setup.py (path:/tmp/pip-NSRUeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:26:37 INFO - Running setup.py (path:/tmp/pip-_OIWUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:26:37 INFO - Running setup.py (path:/tmp/pip-A53pHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:26:37 INFO - Running setup.py (path:/tmp/pip-lUudlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:26:37 INFO - Running setup.py (path:/tmp/pip-qlExKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:26:37 INFO - Running setup.py (path:/tmp/pip-ujRuQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:26:37 INFO - Running setup.py (path:/tmp/pip-v2i_jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:26:37 INFO - Running setup.py (path:/tmp/pip-thxAbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:26:37 INFO - Running setup.py (path:/tmp/pip-yticWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:26:38 INFO - Running setup.py (path:/tmp/pip-iDjIPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:26:38 INFO - Running setup.py (path:/tmp/pip-aZyHZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:26:38 INFO - Running setup.py (path:/tmp/pip-Mac_ys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:26:38 INFO - Running setup.py (path:/tmp/pip-NEddgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:26:38 INFO - Running setup.py (path:/tmp/pip-E7C0p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:26:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:26:38 INFO - Running setup.py install for manifestparser 23:26:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:26:38 INFO - Running setup.py install for mozcrash 23:26:38 INFO - Running setup.py install for mozdebug 23:26:38 INFO - Running setup.py install for mozdevice 23:26:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:26:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:26:38 INFO - Running setup.py install for mozfile 23:26:38 INFO - Running setup.py install for mozhttpd 23:26:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:26:38 INFO - Running setup.py install for mozinfo 23:26:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for mozInstall 23:26:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for mozleak 23:26:39 INFO - Running setup.py install for mozlog 23:26:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for moznetwork 23:26:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for mozprocess 23:26:39 INFO - Running setup.py install for mozprofile 23:26:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for mozrunner 23:26:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:26:39 INFO - Running setup.py install for mozscreenshot 23:26:39 INFO - Running setup.py install for moztest 23:26:40 INFO - Running setup.py install for mozversion 23:26:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:26:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:26:40 INFO - Cleaning up... 23:26:40 INFO - Return code: 0 23:26:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:26:40 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')]} 23:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:40 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')]} 23:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:40 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:40 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 23:26:40 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 23:26:40 INFO - Using env: {'DISPLAY': ':0', 23:26:40 INFO - 'HOME': '/home/cltbld', 23:26:40 INFO - 'LANG': 'en_US.UTF-8', 23:26:40 INFO - 'LANGUAGE': 'en_US:en', 23:26:40 INFO - 'LOGNAME': 'cltbld', 23:26:40 INFO - 'MAIL': '/var/mail/cltbld', 23:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:40 INFO - 'MOZ_NO_REMOTE': '1', 23:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:40 INFO - 'NO_EM_RESTART': '1', 23:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:40 INFO - 'PWD': '/builds/slave/test', 23:26:40 INFO - 'SHELL': '/bin/bash', 23:26:40 INFO - 'SHLVL': '1', 23:26:40 INFO - 'TERM': 'linux', 23:26:40 INFO - 'TMOUT': '86400', 23:26:40 INFO - 'USER': 'cltbld', 23:26:40 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:40 INFO - '_': '/tools/buildbot/bin/python'} 23:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:26:40 INFO - Running setup.py (path:/tmp/pip-0BRimU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:26:40 INFO - Running setup.py (path:/tmp/pip-Pl2Kh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:26:40 INFO - Running setup.py (path:/tmp/pip-bPhclO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:26:40 INFO - Running setup.py (path:/tmp/pip-pgbNcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:26:40 INFO - Running setup.py (path:/tmp/pip-kjObF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:26:40 INFO - Running setup.py (path:/tmp/pip-Na8x_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:26:40 INFO - Running setup.py (path:/tmp/pip-PxGUCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:26:40 INFO - Running setup.py (path:/tmp/pip-UXGC8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:26:40 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)) 23:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:26:40 INFO - Running setup.py (path:/tmp/pip-BJQ3ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:26:41 INFO - Running setup.py (path:/tmp/pip-QGIe2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:26:41 INFO - Running setup.py (path:/tmp/pip-MvtdZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:26:41 INFO - Running setup.py (path:/tmp/pip-xO1A9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:26:41 INFO - Running setup.py (path:/tmp/pip-YmEDg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:26:41 INFO - Running setup.py (path:/tmp/pip-SAv2j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:26:41 INFO - Running setup.py (path:/tmp/pip-W3MZYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:26:41 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:26:41 INFO - Running setup.py (path:/tmp/pip-7HHTfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:26:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 23:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:26:41 INFO - Running setup.py (path:/tmp/pip-iTmRlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:26:41 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)) 23:26:41 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)) 23:26:41 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)) 23:26:41 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)) 23:26:41 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)) 23:26:41 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)) 23:26:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:26:43 INFO - Downloading blessings-1.6.tar.gz 23:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:26:43 INFO - Installing collected packages: blessings 23:26:43 INFO - Running setup.py install for blessings 23:26:43 INFO - Successfully installed blessings 23:26:43 INFO - Cleaning up... 23:26:43 INFO - Return code: 0 23:26:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:26:44 INFO - Reading from file tmpfile_stdout 23:26:44 INFO - Current package versions: 23:26:44 INFO - argparse == 1.2.1 23:26:44 INFO - blessings == 1.6 23:26:44 INFO - blobuploader == 1.2.4 23:26:44 INFO - docopt == 0.6.1 23:26:44 INFO - functools32 == 3.2.3-2 23:26:44 INFO - jsonschema == 2.5.1 23:26:44 INFO - manifestparser == 1.1 23:26:44 INFO - mozInstall == 1.12 23:26:44 INFO - mozcrash == 1.0 23:26:44 INFO - mozdebug == 0.1 23:26:44 INFO - mozdevice == 0.49 23:26:44 INFO - mozfile == 1.2 23:26:44 INFO - mozhttpd == 0.7 23:26:44 INFO - mozinfo == 0.9 23:26:44 INFO - mozleak == 0.1 23:26:44 INFO - mozlog == 3.4 23:26:44 INFO - moznetwork == 0.27 23:26:44 INFO - mozprocess == 0.23 23:26:44 INFO - mozprofile == 0.28 23:26:44 INFO - mozrunner == 6.13 23:26:44 INFO - mozscreenshot == 0.1 23:26:44 INFO - mozsystemmonitor == 0.3 23:26:44 INFO - moztest == 0.8 23:26:44 INFO - mozversion == 1.4 23:26:44 INFO - psutil == 3.1.1 23:26:44 INFO - requests == 1.2.3 23:26:44 INFO - wsgiref == 0.1.2 23:26:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:26:44 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')]} 23:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:44 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')]} 23:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:44 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:44 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 23:26:44 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 23:26:44 INFO - Using env: {'DISPLAY': ':0', 23:26:44 INFO - 'HOME': '/home/cltbld', 23:26:44 INFO - 'LANG': 'en_US.UTF-8', 23:26:44 INFO - 'LANGUAGE': 'en_US:en', 23:26:44 INFO - 'LOGNAME': 'cltbld', 23:26:44 INFO - 'MAIL': '/var/mail/cltbld', 23:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:44 INFO - 'MOZ_NO_REMOTE': '1', 23:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:44 INFO - 'NO_EM_RESTART': '1', 23:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:44 INFO - 'PWD': '/builds/slave/test', 23:26:44 INFO - 'SHELL': '/bin/bash', 23:26:44 INFO - 'SHLVL': '1', 23:26:44 INFO - 'TERM': 'linux', 23:26:44 INFO - 'TMOUT': '86400', 23:26:44 INFO - 'USER': 'cltbld', 23:26:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:44 INFO - '_': '/tools/buildbot/bin/python'} 23:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 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)) 23:26:44 INFO - Cleaning up... 23:26:44 INFO - Return code: 0 23:26:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:26:44 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')]} 23:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:44 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')]} 23:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:44 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 0x22cf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2641630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', '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 23:26:44 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 23:26:44 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 23:26:44 INFO - Using env: {'DISPLAY': ':0', 23:26:44 INFO - 'HOME': '/home/cltbld', 23:26:44 INFO - 'LANG': 'en_US.UTF-8', 23:26:44 INFO - 'LANGUAGE': 'en_US:en', 23:26:44 INFO - 'LOGNAME': 'cltbld', 23:26:44 INFO - 'MAIL': '/var/mail/cltbld', 23:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:44 INFO - 'MOZ_NO_REMOTE': '1', 23:26:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:26:44 INFO - 'NO_EM_RESTART': '1', 23:26:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:44 INFO - 'PWD': '/builds/slave/test', 23:26:44 INFO - 'SHELL': '/bin/bash', 23:26:44 INFO - 'SHLVL': '1', 23:26:44 INFO - 'TERM': 'linux', 23:26:44 INFO - 'TMOUT': '86400', 23:26:44 INFO - 'USER': 'cltbld', 23:26:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:44 INFO - '_': '/tools/buildbot/bin/python'} 23:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:26:44 INFO - Cleaning up... 23:26:44 INFO - Return code: 0 23:26:44 INFO - Running post-action listener: _resource_record_post_action 23:26:44 INFO - Running post-action listener: _start_resource_monitoring 23:26:44 INFO - Starting resource monitoring. 23:26:44 INFO - [mozharness: 2017-01-07 07:26:44.626767Z] Finished create-virtualenv step (success) 23:26:44 INFO - [mozharness: 2017-01-07 07:26:44.627299Z] Running install step. 23:26:44 INFO - Running pre-action listener: _resource_record_pre_action 23:26:44 INFO - Running main action method: install 23:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:26:44 INFO - Reading from file tmpfile_stdout 23:26:44 INFO - Detecting whether we're running mozinstall >=1.0... 23:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:26:44 INFO - Reading from file tmpfile_stdout 23:26:44 INFO - Output received: 23:26:44 INFO - Usage: mozinstall [options] installer 23:26:44 INFO - Options: 23:26:44 INFO - -h, --help show this help message and exit 23:26:44 INFO - -d DEST, --destination=DEST 23:26:44 INFO - Directory to install application into. [default: 23:26:44 INFO - "/builds/slave/test"] 23:26:44 INFO - --app=APP Application being installed. [default: firefox] 23:26:44 INFO - mkdir: /builds/slave/test/build/application 23:26:44 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'] 23:26:44 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 23:27:02 INFO - Reading from file tmpfile_stdout 23:27:02 INFO - Output received: 23:27:02 INFO - /builds/slave/test/build/application/firefox/firefox 23:27:02 INFO - Running post-action listener: _resource_record_post_action 23:27:02 INFO - [mozharness: 2017-01-07 07:27:02.280925Z] Finished install step (success) 23:27:02 INFO - [mozharness: 2017-01-07 07:27:02.281194Z] Running run-tests step. 23:27:02 INFO - Running pre-action listener: _resource_record_pre_action 23:27:02 INFO - Running main action method: run_tests 23:27:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:27:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:27:02 INFO - Python 2.7.3 23:27:02 INFO - Return code: 0 23:27:02 INFO - grabbing minidump binary from tooltool 23:27:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:02 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 0x26ca850>, '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 0x263f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x263fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:27:02 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 23:27:02 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 23:27:02 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 23:27:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:27:02 INFO - Return code: 0 23:27:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:27:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:27:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:27:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:27:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:27:02 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 23:27:02 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 23:27:02 INFO - Using env: {'DISPLAY': ':0', 23:27:02 INFO - 'HOME': '/home/cltbld', 23:27:02 INFO - 'LANG': 'en_US.UTF-8', 23:27:02 INFO - 'LANGUAGE': 'en_US:en', 23:27:02 INFO - 'LOGNAME': 'cltbld', 23:27:02 INFO - 'MAIL': '/var/mail/cltbld', 23:27:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:27:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:02 INFO - 'MOZ_NO_REMOTE': '1', 23:27:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:27:02 INFO - 'NO_EM_RESTART': '1', 23:27:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:02 INFO - 'PWD': '/builds/slave/test', 23:27:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:27:02 INFO - 'SHELL': '/bin/bash', 23:27:02 INFO - 'SHLVL': '1', 23:27:02 INFO - 'TERM': 'linux', 23:27:02 INFO - 'TMOUT': '86400', 23:27:02 INFO - 'USER': 'cltbld', 23:27:02 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885', 23:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:02 INFO - '_': '/tools/buildbot/bin/python'} 23:27:02 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/1483767014/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 23:27:02 INFO - mozversion application_buildid: 20170106213014 23:27:02 INFO - mozversion application_changeset: 6f417be4dd3d5c77201c9b141687cccad6fc504d 23:27:02 INFO - mozversion application_display_name: Nightly 23:27:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:27:02 INFO - mozversion application_name: Firefox 23:27:02 INFO - mozversion application_remotingname: firefox 23:27:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:27:02 INFO - mozversion application_vendor: Mozilla 23:27:02 INFO - mozversion application_version: 53.0a1 23:27:02 INFO - mozversion platform_buildid: 20170106213014 23:27:02 INFO - mozversion platform_changeset: 6f417be4dd3d5c77201c9b141687cccad6fc504d 23:27:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:27:02 INFO - mozversion platform_version: 53.0a1 23:27:02 INFO - using testdate: 1483774022 23:27:02 INFO - actual date: 1483774022 23:27:02 INFO - starting webserver on 'localhost:45586' 23:27:02 INFO - SUITE-START | Running 2 tests 23:27:02 INFO - TEST-START | dromaeo_css 23:27:02 INFO - operating with platform_type : linux_ 23:27:02 INFO - Initialising browser for dromaeo_css test... 23:27:02 INFO - TEST-INFO | started process 7177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKIZUBO/profile http://localhost:45586/getInfo.html) 23:27:03 INFO - PROCESS | 7177 | 1483774023047 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 23:27:06 INFO - PROCESS | 7177 | __metrics Screen width/height:1600/1200 23:27:06 INFO - PROCESS | 7177 | colorDepth:24 23:27:06 INFO - PROCESS | 7177 | Browser inner width/height: 1024/697 23:27:06 INFO - PROCESS | 7177 | __metrics 23:27:06 INFO - PROCESS | 7177 | 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" 23:27:06 INFO - PROCESS | 7177 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:27:07 INFO - PROCESS | 7177 | console.error: PushService: 23:27:07 INFO - PROCESS | 7177 | stateChangeProcessEnqueue: Error transitioning state 23:27:07 INFO - PROCESS | 7177 | UnknownError 23:27:08 INFO - TEST-INFO | 7177: exit 0 23:27:08 INFO - Browser initialized. 23:27:08 INFO - Running cycle 1/1 for dromaeo_css test... 23:27:08 INFO - TEST-INFO | started process 7262 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKIZUBO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:27:19 INFO - PROCESS | 7262 | RSS: Main: 147259392 23:27:19 INFO - PROCESS | 7262 | 23:30:22 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45586/tests/dromaeo/cssquery-ext.html) 23:30:23 INFO - PROCESS | 7262 | RSS: Main: 235696128 23:30:23 INFO - PROCESS | 7262 | 23:32:08 INFO - PROCESS | 7262 | ************************* 23:32:08 INFO - PROCESS | 7262 | A coding exception was thrown and uncaught in a Task. 23:32:08 INFO - PROCESS | 7262 | 23:32:08 INFO - PROCESS | 7262 | Full message: ReferenceError: fetch is not defined 23:32:08 INFO - PROCESS | 7262 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 23:32:08 INFO - PROCESS | 7262 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 23:32:08 INFO - PROCESS | 7262 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 23:32:08 INFO - PROCESS | 7262 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:32:08 INFO - PROCESS | 7262 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:32:08 INFO - PROCESS | 7262 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:32:08 INFO - PROCESS | 7262 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 23:32:08 INFO - PROCESS | 7262 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:32:08 INFO - PROCESS | 7262 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:32:08 INFO - PROCESS | 7262 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:32:08 INFO - PROCESS | 7262 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 23:32:08 INFO - PROCESS | 7262 | 23:32:08 INFO - PROCESS | 7262 | ************************* 23:33:25 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-ext.html (next: http://localhost:45586/tests/dromaeo/cssquery-jquery.html) 23:33:26 INFO - PROCESS | 7262 | RSS: Main: 239194112 23:33:26 INFO - PROCESS | 7262 | 23:36:28 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45586/tests/dromaeo/cssquery-mootools.html) 23:36:28 INFO - PROCESS | 7262 | RSS: Main: 220651520 23:36:28 INFO - PROCESS | 7262 | 23:39:30 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45586/tests/dromaeo/cssquery-prototype.html) 23:39:31 INFO - PROCESS | 7262 | RSS: Main: 229257216 23:39:31 INFO - PROCESS | 7262 | 23:42:34 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45586/tests/dromaeo/cssquery-yui.html) 23:42:34 INFO - PROCESS | 7262 | RSS: Main: 183566336 23:42:34 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | Cycle 1(1): loaded http://localhost:45586/tests/dromaeo/cssquery-yui.html (next: http://localhost:45586/tests/dromaeo/cssquery-dojo.html) 23:45:37 INFO - PROCESS | 7262 | RSS: Main: 273588224 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | __start_tp_report 23:45:37 INFO - PROCESS | 7262 | _x_x_mozilla_page_load 23:45:37 INFO - PROCESS | 7262 | _x_x_mozilla_page_load_details 23:45:37 INFO - PROCESS | 7262 | |i|pagename|runs| 23:45:37 INFO - PROCESS | 7262 | |0;dojo.html;3186.6335371432283;3215.9517607235894;3224.274538228898;3252.837358132093;3271.1004473769713;5341.893162136755;5406;5472.0423925812975;5518.089515229983;5550.2784637997;5406.972965135169;5493.478119578644;5496.393678447981;5499.7250137493165;5523.889522209553;4977.203647416414;5008.073506401309;5023.120953833074;5054.21659642756;5069.949300506984;5036.798528058874;5060.620453466005;5115.9744201278945;5128.384593848725;5158.252053452247;5102.464241254652;5162;5165.896682066356;5174.172132458807;5195.064888320101;4889.8777530561665;4923.384576927884;4943.307936888838;5013.323201367805;5035.974820125876;5031.773570189313;5076.187809950427;5078.238264260353;5083.135866902636;5085.389753229636;5449.591280653967;5540.974919639839;5550.27846379972;5554.833354999357;5559.33288005442;5709.486146246816;5940.138679891392;5941.8811623767715;5945.108233764927;5946.108083787423;5816.331121920991;5875.941240587625;5930.347661757203;5954.4938680212235;6054.939450605481;5570.888582228414;5593.161025846074;5593.188987596816;5596.076647353182;5599.636023658449;6048.243969503812;6101.206843110367;6109.541784366191;6112.480439162721;6117.449429551361;5658.151277308354;5719.942800572025;5723.198752174699;5804.535637149018;5808.128780682847;5846.766129354778;5871.324797648325;5889.734961926723;5898.793542226002;5955.106733989849;5704.401037890962;5779.913301300484;5781.421857814269;5801.071828507418;6020.909686354708;5836.2412886325355;5928.881422371528;5931.40685931412;5940.31686356066;5981.7607295707685;4577.22187228172;4579.450465944042;4582.977085114553;4585.701929374647;4622.168009758208;4010.47863777707;4031.133306339151;4050.1494686115657;4080.510338759368;4121.29937910556;5502.972485137549;5557.110862262019;5564.610477266552;5597.160425936142;5601.719914004283;5925.34821169671;5933.3473317934995;5937.2578427696535;5938;5940.168376427304;5324.946750532445;5363.249145119687;5544.528715059185;5550.833474995757;5553.555715542747;4994.425641051253;4998.500149984972;5055.646104772704;5070.670406423645;5128.102582048126;6108.633481991094;6165.630062196283;6166.81499555014;6184.690765461709;6190.783322583688;5815.3603103657715;5910.054391297372;5933.851653708692;5938.138969849261;5994.910076348771;5897.587168898136;5911.083782013795;6059.0608455689435;6152.077188421773;6169.722362493789;5737.311522617312;5878.294604647299;5885.058390657474;5917.5857689961285;5920.437558431942;5954.07711804671;5964.403559644001;5967.194428752063;5967.254093238345;5971.193888824953;5559.44405559457;5630.85922851932;5746.051901436216;5794.681292528951;5803.593748437737;5707.6290041147195;5766.106253530709;5766.365699773106;5838.5621078418435;5845.707714614167;5520.696361700145;5803.622764520293;5809.912851307149;5815;5819.5344372450945;5682.886342273048;5731.627444216113;5780.826575202751;5832.504237139721;5836.737346819318;5514.889702206014;5579.916301255404;5604.271444712162;5722;5723.427657234243 23:45:37 INFO - PROCESS | 7262 | |1;ext.html;562.3689548985935;573.3521121133119;590.0176206615982;604.4650484321375;615.1725928625997;34381.48427773582;43633.78183109088;44717.10565788687;44864.3270350945;44869.551304486944;46488.30267545981;46997.76501117498;47048.76475617617;47095.76452117743;47115;8583.098774628668;8927.642894284238;9114.270858331318;9129.452232866017;9131.908680913173;8415.368847336444;10677.733056673567;10877.945610271978;10896.18278629102;11273.37996410197;10793.352398856054;10829;10837.729056773565;10841.349519028845;10868.07621352186;10826.512806923707;10832.458377081115;10870.184736144742;10892.346459212473;10938.398388088612;10546.947265263625;10578.57685692579;10603.522841472151;10610.469476526143;10613.097886679641;43002.35496467556;43039;43042.569574304485;43150.56849431497;43160.56839431597;4233.978830105861;4268.253055715238;4324.437823083011;4400.691951563392;4427.380166776654;32757;32809.50785738216;33743.15642108942;34203.65796342055;34776.30447391063;4243.702940794145;4286.142771445723;4327.415798867144;4363.258246098171;4452.109578084396;343452;343630;343698;344509;345305.9999999975;60719.392806072254;61194.0820887687;61211.38788612146;61237;61376;63329.68335158295;63349;63434.68282658557;63440.68279658572;63583.36416635867;15809.525714228592;16349.591260218589;16350.75473867893;16370.345186192419;16385.262663180187;11874.990625796903;11923.105767067505;12072.939635301767;12236.81644775329;12455.564055258023;15970.843936068715;16448.917755411145;16624.418145364853;16626.66986641066;16653;6300.9684951574945;6329.3354197809485;6436.613803171825;6525.967370163119;6566.704498297588;932.3659911260409;935.2050756856618;936.2135805922921;954.9761255968517;957.1816097236901;30224.546631800542;32473.35053298968;32500.34999300048;32724.345513089604;33086.66913330836;1040.953157107932;1104.8232282834708;1105.8838821923744;1113.0817075912405;1114.8940850619178;17079.231434585472;17098.743518847226;17292.827071729374;17309.480715578553;17361.3923512679;488471;489222;489247;490882;492106;63178;63531.682341588;63665.681671591345;63735.68132159402;63784.68107659525;1443.8050863133553;1461.554225961092;1467.5450610310838;1504.277946585645;1515.113658509784;30013.849930750206;31730.365392692478;32060.358792823547;32211.35577288488;32385.51421728723;51333.743331283105;52867.735661322986;52886;52891.206631901325;52959.470405295455;379675;381687;381931;382640;383276;36039.27921441609;36943.81528092342;39936.40095398513;40694.79652601718;41884.371734423396;48881.26678099902;49343.50656493533;49743;50621.49378506168;50794.74602626963;40165.79917100396;40911.59088409078;41458.79270603628;41740;43243;42137.36793948032;42347;42596;42835.571644284406;43098;40901.38647920343;41456.1708765829;41960.58039419567;42040.579594203664;42075;34342.82828585841;35726;36190.27619447544;36418.4537231947;37065;27349.589756154073;27431;27641.308967275978;27682.446351072464;27789.305267368476 23:45:37 INFO - PROCESS | 7262 | |2;jquery.html;426.3428818421156;427.09242858924785;437.48376915240016;437.66081287002567;447.1571088498182;19238.615227695456;19879.900600496996;20927;21108;21353.893230533846;19495.512612184666;20486.2829800957;20842.166313347487;21262.681059784114;21554.56890862182;5787.565932555054;5983.58114931955;6005.069214271794;6020.277566692003;6021.307549631782;5796.594238403313;5874.206982057411;5913.645181289115;5921.542539988294;5923.289205295349;5864.941350586503;5866.237389139406;5875.88248235035;5878.353381128072;5881.294244690643;5872.236609240793;5961.493273071794;6202.3487533808975;6233.657148856811;6336.144620476222;6044.455998960115;6094.664793436359;6154.7230374633245;6180.87638247237;6183.907241391383;14395.712085758227;14561.271936403138;14631.561053168421;14701;14702.63243418923;2545.9363515912064;2680.772134368581;2825.9152225433268;3037.2862377341307;3065.6167979002626;10725.463726813628;10970.122390208764;10973.615923442641;10982.615608453665;11009.61466348682;1904.7999960004138;1982.2764690887866;2019.2730616977876;2026.7466566679166;2061.4537147655883;89382.5530872348;90913;92380;93879.53060234655;96780.51609741907;11597.884021159849;11694.47374868116;11730.589429369931;11895.762084758257;12112.81830772539;16672.416465423652;16688.83311166897;16693.916530417515;16697.499075027525;16720.41478548245;8345.582720863933;8509.702160424355;8512.361572881935;8735.475871447607;8795.252403545643;7944.721934732257;8015.238552337519;8195.180481951757;8228.50628962264;8254.380921430797;4981.352424184905;5062.620303477254;5085.4914508548845;5104.923426148611;5157.200633901752;2055.2498338106484;2063.329417939146;2118.671605901088;2132.285684295747;2134.4343748906554;939.5818860607101;951.3816019587324;973.7176218896583;975.9951200244095;980.3578655980344;18922.432327030212;20801;20900.790992089886;20950.790492094882;21208.15167393318;956.7464621875208;975.8341082016075;978.2369751593768;982.5529384130347;991.1327588360185;10889.401082940356;11747.17769756126;11844.585439509576;11847;11850.525978960917;584974;617756;662417;663465;684956;63166.684166578874;67380;100169;106229;109413;992.275638783692;1016.9389836609679;1066.5253961986996;1068.417712346773;1072.7050061233224;18434.631307374046;18472.722909156106;18690.915998860037;18708.906455467637;19295.90352048231;45024.54975450203;45424;45539;45831.770841146914;45894.050660120956;62362;63289.36710632835;63705.36294636994;65017;65022.34977650163;5936.525077993664;6872.862542749217;8670.132986701532;8786.472811631322;9037.593308301011;12361.93819030899;13153.93423032917;13330.333483325601;13690.726185476036;13810.792838107638;11816.76366472683;11817.231879927776;11867.228630139014;12051.276923384625;12422.06834487399;11672.358020308964;11685.883141168819;11919.523219071487;12019.338936358234;12510.937445312715;11820.40897955116;11893.76212475763;12189;12211.633650990485;12329.568465103679;8495.702650407453;8671.21959023691;8730.388872778845;8863.069377715505;8938.68714594986;10719.624813131502;10855.837162442413;11089.5564177432;11827.467763950472;12096.818547721616 23:45:37 INFO - PROCESS | 7262 | |3;mootools.html;1443.5236371997241;1467.1417220925757;1470.3530446603493;1479.726250643631;1490.7242160200365;8040.195980401957;8503.74488765338;8504.36217283703;8510.148985101487;8513.489190648557;7422.36909862661;7649.08211014679;8083.838323233532;8289.295409890166;8323.791905202374;2403.8677872716994;2428.077330614373;2431.234161239204;2440.0971640493044;2442.0842184180933;2375.8574485530835;2393.7247216570145;2408.2414039577475;2414.106780491221;2414.3481260059775;2379.6192609182535;2379.726331471877;2400.459896523286;2402.7116745990506;2403.146882856578;2210.082815631511;2218.7004754358118;2236.284388995514;2243.9663405048937;2257.3905045637584;2327.2552783109413;2328.7554806745216;2362.70466191726;2363.2673871100014;2363.3618922890723;8402.411831171821;8492.575371231414;8494.872576911352;8499.36254780894;8503.48979061252;5135.922961155586;5277.076511610453;5283.709395983219;5287.154055351164;5288.444713305086;3392.4741665041956;3415.077928959167;3442.8106454145013;3453.3783918894587;3503.5095086687884;1458.8541145885326;1490.9403623855142;1514.8788096952217;1545.768134779781;1576.2118940529735;11302.660920172408;11447.542098316057;11486.540538378455;11506.76986460266;11528.654140375802;4494.696398147359;4742.549457801503;4753.168195565762;4827.42070951488;4849.157320218294;9693.176080033276;9702.223822094216;9712.562934667956;9717.6598819041;9719.708408747749;5259.184826351921;5290.49740274681;5314.707691077027;5315.840524784263;5320.866978325573;4434.667399945017;4450.799714012813;4771.33201351811;4821.228603423436;4829.975850120727;3956.5054368203973;3970.3250447424007;3971.5631280559114;4072.429859819628;4077.26609210345;1321.7752981993085;1343.1806597975228;1344.205485676613;1357.5384369314481;1364.1746743220408;989.8218320702199;993.6969224386631;1001.1540248490014;1007.9143272821746;1016.4104819204844;5641.379448260688;5773.1051686988585;5876.382979787147;5919.67441790697;5974.492168165754;1041.156663102889;1059.1791361694595;1069.395791377869;1081.7187531241934;1086.0876863434594;3927.4501569780255;3952.822123004472;4022.6379625833815;4024.5170579530645;4119.402686610425;10053.798924021625;10277.331973421704;10277.845832312521;10307.175425965905;10358.067773900235;10194.898051019543;10216.948915255376;10269.794604107876;10307.896921030844;10313.896861031444;7636.656350464243;7924.207579242145;7924.920750792418;8080.676772929134;8085.878711819328;5767.625104368202;5883.588148829541;5917.6745279010065;5966.49284810796;6019.608725432833;9406.529673516434;9480.668176613786;9509.572069256761;9513.143817056498;9513.143817056498;9340.206082483064;9400.482973436254;9425.57584908667;9556.665516706882;9595.616175353187;6868.244493105654;7041.647917604202;7304.3791277739865;7512.624368781429;7560.6219689016425;8128;8313.251807337369;8319.251267385964;8319.334453243848;8328.042275138436;7321.560706357635;7346.375558077623;7439.739609113872;7571.886421703569;7602.429817763579;7261.636918154177;7299.927000729925;7360.77917662471;7367.926320736724;7470.4770666052855;6117.082437634391;6134.171886795226;6137.877242455036;6155.72299246526;6192.195014648067;5101.311322971352;5142.434332223377;5161.741912904265;5203.661761985459;5417.51242388187;5185.948140518546;5194.948050519598;5248.89502209946;5322.866928326823;5404.918926216189 23:45:37 INFO - PROCESS | 7262 | |4;prototype.html;398.2214770124405;405.37572138906086;411.116100384174;411.43016921563634;416.5147603042455;5316.3620365556135;5385.7576409061585;5387.353517577886;5388.353397592285;5425.321834770653;5209;5320.893582128355;5320.998261634071;5325.840224793253;5327.493888080636;4147.2327619390435;4286;4287.078278170193;4289.742615443083;4317.481902171744;4294.785260736966;4321.762303073329;4361.280388735855;4385.298352263639;4391.802368893392;4334.869953901388;4335.875421043682;4340.392345071693;4350.173467041268;4366.1922544329245;4014.1770936958;4133.565975572582;4202.936955945633;4215.620594146541;4385;3713.3316003119426;3716.2939041582013;3722.7394082414244;3723.8510459581585;3730.123420996064;5213.218017297436;5235.424103348629;5237.083510385705;5237.502437268491;5244.213367994793;4599.931001034987;4765.833195838129;4800.183968725325;4813.243166244296;4827.25177597473;5594.356648985337;5608.551315894719;5610.747516361774;5620.9437905620825;5657.886842263131;4072.8981775455554;4080.12277360366;4080.9387859182134;4084.305668036441;4103.158852435244;5745.6552606843725;5777.7977770777825;5807.274090738658;5845.444682755174;5874.676892770896;4130.607592278729;4132.834686612502;4133.731307465006;4138.296489596776;4148.647364973951;4179.623833854967;4202.285611446062;4205.17998990194;4208.936865947013;4212.178625168124;4538.364628951949;4754.857354279377;4789.185838407479;4797.496262892416;4810.374651295379;5645.604807663506;5645.673266782303;5648.548116150698;5651.745671444794;5659.603827732101;1410.9840914541514;1419.2619837684347;1419.9077059991068;1425.5580769961346;1429.8498657640798;929.693201243588;990.3265779270165;991.2862738828032;994.4679596415883;994.5126887824914;5121.77077501397;5186.974065129651;5188.45521220274;5193.324867767166;5202.76587553561;5518.061929471999;5529.723513824292;5582.804601838916;5675.77296908119;5691.544676425876;5046.44489106198;5170.353705786777;5180.766865491062;5212.514433386543;5257.8159764408065;2947.8083924544835;2960.9703902961123;2964.9110526684235;2965.303153758865;2976.538636511344;5655.830325090254;5810.738516766755;5813.12803079533;5821.7380217890295;5869.706514674249;5461.672299662033;5656.490915817596;5725.2843394575675;5726.625609853689;5738.368779434259;5732.512736417368;5890.764369425261;5916.290045194518;5949.524038076857;5963.821085367533;5727.198192253171;5728.598998070095;5744.339400968942;5746.879358525048;5770.711464426846;5460.180972854104;5595.776168953196;5639.520640745419;5680.829575112753;5693.405846363025;5905.202797622266;5920.2895652521975;5927.4665280125;5971.28344598651;5975.312839023568;4158.854440094582;4167.166566686736;4170.958290417057;4177.68667349956;4186.267403204488;5560.304961879765;5698.487136157766;5737.655740655574;5742.454466825645;5766.596338256281;5557.666160121623;5569.354556585589;5585.748641311069;5601.151619965321;5643.830685079454;5336.573074154136;5463.754131064032;5582.888342233051;5608.46719561641;5660.660360378226;5543.72281385921;5557.637629234255;5574.358948720786;5607.943920560742;5609.747561359667;5595.76893083521;5607.990561698934;5671.234383358359;5704.1443783432815;5726.541876649775;5288.338957630296;5443.755031023693;5446.50981411659;5456.29913192972;5513.365962914156 23:45:37 INFO - PROCESS | 7262 | |5;yui.html;755.8412733326002;764.9847003059942;781.8749000159972;878.1306506558507;896.1397058823529;15673.294701738421;15973.281202345908;16071.919640401782;16207.83792162081;16960.067196304197;15316.923415382907;15777.447789327376;15994.280257388416;16022.919885400557;16187.91906040471;739.6782399656154;745.6458182363361;746.264929044893;746.5483382553558;746.7199800074972;649.6524359467686;664.2560332427688;731.3965978068088;751.0874287740394;754.6679461037154;785.7485604606528;786.2766255045343;786.6106277392698;787.1262898183012;787.4763282417185;779.9961000194962;780.4185881518248;780.5394817057935;780.5745868501651;780.8945792318021;492.477973348252;496.57294726535514;554.8917960997607;566.3713278261126;577.8208755285875;16217.270222840001;16220.675586488322;16234.675306493922;16235;16247.83752162487;13986.930065349608;14073.155610663393;14191.71616567663;14702.411903523845;14932.776008359884;12477.875221247763;12906.354682265943;12924.676883077998;13062.150960187557;13067.346632668328;3829.48301979232;3831.4252862070634;3832.770033797981;3903.0632648164237;3904.4338570907344;82805.17194828035;82922.58538707328;83829;86021.13978860257;86662;12047.457864396123;12131.939340303243;12146.757064858653;12209.69475763113;12224.1443098984;23481.41296467602;23557.175498857458;23562.758703433203;23567.882160589088;23586.88206558956;8226.958865205635;8426.789330266793;8529.701460448854;8641.913580864111;8732.257758090507;4210.621044106045;4222.451081359465;4321.611055005064;4332.545082766327;4347.999960009216;3584.175639602906;3642.5082613846794;3675.8162091895297;3677.786330510925;3678.70570354371;625.8623102917351;628.9339619339903;632.3644737039338;633.7718421368396;660.9372109649576;756.6632848382417;762.7254188492138;766.3677466090386;770.2451597434433;791.2799352589117;9898.802023959624;10442.582296708199;10602.469876506144;10969.616063437901;11011.779764404668;722.8156820010969;747.517850986111;750.3873206321834;752.0749478141917;763.1414658509177;4616.3998680172035;4675.275332323496;4998.750062496861;5018.397792264878;5020.623453241022;81290;82614;82732;85773;86438.56780716182;57437;61396.693016534635;62013;71154;72377.63811180911;62350.68824655938;62849;63116;63596.36403635997;63621.68189159025;9157.267418606629;9531.189848862925;9542;9617.422954622465;9700.708978730649;36484.45273320849;37091.814540927124;37250.44124338083;40399.1920161601;41786;49561.75219123881;49700.75149624229;50101;52878.73560632172;53813.203930026924;13246.403911824184;13430.529931452353;13617.931910340385;13689.726205475636;13700.794488082487;14938;15235.238238088274;15268.770968435705;15294.847051529787;15591.532254032398;13229.470821167046;13364.732705345645;13670.384832682354;13898.930505347409;14195.148291102567;14041.789373159616;14112.929435352757;14200.360983755549;14449.422023118957;14525.418983240974;13889.444422222998;13977.650558736113;14152;14188.290585470892;14537.563873083824;11666.70833229176;11841.46713397882;11997.220180688228;12147.817782733442;12393.008559315052;11725.530978760755;11746.434421317676;11997.700057498283;12035.518579257083;12039.157258991787 23:45:37 INFO - PROCESS | 7262 | __end_tp_report 23:45:37 INFO - PROCESS | 7262 | __start_cc_report 23:45:37 INFO - PROCESS | 7262 | _x_x_mozilla_cycle_collect,895 23:45:37 INFO - PROCESS | 7262 | __end_cc_report 23:45:37 INFO - PROCESS | 7262 | __startTimestamp1483775137414__endTimestamp 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | ------- Summary: start ------- 23:45:37 INFO - PROCESS | 7262 | Number of tests: 6 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#0] dojo.html Cycles:165 Average:5490.10 Median:5682.89 stddev:612.08 (10.8%) stddev-sans-first:586.67 23:45:37 INFO - PROCESS | 7262 | Values: 3186.6 3216.0 3224.3 3252.8 3271.1 5341.9 5406.0 5472.0 5518.1 5550.3 5407.0 5493.5 5496.4 5499.7 5523.9 4977.2 5008.1 5023.1 5054.2 5069.9 5036.8 5060.6 5116.0 5128.4 5158.3 5102.5 5162.0 5165.9 5174.2 5195.1 4889.9 4923.4 4943.3 5013.3 5036.0 5031.8 5076.2 5078.2 5083.1 5085.4 5449.6 5541.0 5550.3 5554.8 5559.3 5709.5 5940.1 5941.9 5945.1 5946.1 5816.3 5875.9 5930.3 5954.5 6054.9 5570.9 5593.2 5593.2 5596.1 5599.6 6048.2 6101.2 6109.5 6112.5 6117.4 5658.2 5719.9 5723.2 5804.5 5808.1 5846.8 5871.3 5889.7 5898.8 5955.1 5704.4 5779.9 5781.4 5801.1 6020.9 5836.2 5928.9 5931.4 5940.3 5981.8 4577.2 4579.5 4583.0 4585.7 4622.2 4010.5 4031.1 4050.1 4080.5 4121.3 5503.0 5557.1 5564.6 5597.2 5601.7 5925.3 5933.3 5937.3 5938.0 5940.2 5324.9 5363.2 5544.5 5550.8 5553.6 4994.4 4998.5 5055.6 5070.7 5128.1 6108.6 6165.6 6166.8 6184.7 6190.8 5815.4 5910.1 5933.9 5938.1 5994.9 5897.6 5911.1 6059.1 6152.1 6169.7 5737.3 5878.3 5885.1 5917.6 5920.4 5954.1 5964.4 5967.2 5967.3 5971.2 5559.4 5630.9 5746.1 5794.7 5803.6 5707.6 5766.1 5766.4 5838.6 5845.7 5520.7 5803.6 5809.9 5815.0 5819.5 5682.9 5731.6 5780.8 5832.5 5836.7 5514.9 5579.9 5604.3 5722.0 5723.4 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#1] ext.html Cycles:180 Average:58278.10 Median:40906.49 stddev:108136.10 (264.3%) stddev-sans-first:108352.62 23:45:37 INFO - PROCESS | 7262 | Values: 562.4 573.4 590.0 604.5 615.2 34381.5 43633.8 44717.1 44864.3 44869.6 46488.3 46997.8 47048.8 47095.8 47115.0 8583.1 8927.6 9114.3 9129.5 9131.9 8415.4 10677.7 10877.9 10896.2 11273.4 10793.4 10829.0 10837.7 10841.3 10868.1 10826.5 10832.5 10870.2 10892.3 10938.4 10546.9 10578.6 10603.5 10610.5 10613.1 43002.4 43039.0 43042.6 43150.6 43160.6 4234.0 4268.3 4324.4 4400.7 4427.4 32757.0 32809.5 33743.2 34203.7 34776.3 4243.7 4286.1 4327.4 4363.3 4452.1 343452.0 343630.0 343698.0 344509.0 345306.0 60719.4 61194.1 61211.4 61237.0 61376.0 63329.7 63349.0 63434.7 63440.7 63583.4 15809.5 16349.6 16350.8 16370.3 16385.3 11875.0 11923.1 12072.9 12236.8 12455.6 15970.8 16448.9 16624.4 16626.7 16653.0 6301.0 6329.3 6436.6 6526.0 6566.7 932.4 935.2 936.2 955.0 957.2 30224.5 32473.4 32500.3 32724.3 33086.7 1041.0 1104.8 1105.9 1113.1 1114.9 17079.2 17098.7 17292.8 17309.5 17361.4 488471.0 489222.0 489247.0 490882.0 492106.0 63178.0 63531.7 63665.7 63735.7 63784.7 1443.8 1461.6 1467.5 1504.3 1515.1 30013.8 31730.4 32060.4 32211.4 32385.5 51333.7 52867.7 52886.0 52891.2 52959.5 379675.0 381687.0 381931.0 382640.0 383276.0 36039.3 36943.8 39936.4 40694.8 41884.4 48881.3 49343.5 49743.0 50621.5 50794.7 40165.8 40911.6 41458.8 41740.0 43243.0 42137.4 42347.0 42596.0 42835.6 43098.0 40901.4 41456.2 41960.6 42040.6 42075.0 34342.8 35726.0 36190.3 36418.5 37065.0 27349.6 27431.0 27641.3 27682.4 27789.3 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#2] jquery.html Cycles:180 Average:33877.60 Median:3051.45 stddev:105695.79 (3463.8%) stddev-sans-first:105962.45 23:45:37 INFO - PROCESS | 7262 | Values: 426.3 427.1 437.5 437.7 447.2 19238.6 19879.9 20927.0 21108.0 21353.9 19495.5 20486.3 20842.2 21262.7 21554.6 5787.6 5983.6 6005.1 6020.3 6021.3 5796.6 5874.2 5913.6 5921.5 5923.3 5864.9 5866.2 5875.9 5878.4 5881.3 5872.2 5961.5 6202.3 6233.7 6336.1 6044.5 6094.7 6154.7 6180.9 6183.9 14395.7 14561.3 14631.6 14701.0 14702.6 2545.9 2680.8 2825.9 3037.3 3065.6 10725.5 10970.1 10973.6 10982.6 11009.6 1904.8 1982.3 2019.3 2026.7 2061.5 89382.6 90913.0 92380.0 93879.5 96780.5 11597.9 11694.5 11730.6 11895.8 12112.8 16672.4 16688.8 16693.9 16697.5 16720.4 8345.6 8509.7 8512.4 8735.5 8795.3 7944.7 8015.2 8195.2 8228.5 8254.4 4981.4 5062.6 5085.5 5104.9 5157.2 2055.2 2063.3 2118.7 2132.3 2134.4 939.6 951.4 973.7 976.0 980.4 18922.4 20801.0 20900.8 20950.8 21208.2 956.7 975.8 978.2 982.6 991.1 10889.4 11747.2 11844.6 11847.0 11850.5 584974.0 617756.0 662417.0 663465.0 684956.0 63166.7 67380.0 100169.0 106229.0 109413.0 992.3 1016.9 1066.5 1068.4 1072.7 18434.6 18472.7 18690.9 18708.9 19295.9 45024.5 45424.0 45539.0 45831.8 45894.1 62362.0 63289.4 63705.4 65017.0 65022.3 5936.5 6872.9 8670.1 8786.5 9037.6 12361.9 13153.9 13330.3 13690.7 13810.8 11816.8 11817.2 11867.2 12051.3 12422.1 11672.4 11685.9 11919.5 12019.3 12510.9 11820.4 11893.8 12189.0 12211.6 12329.6 8495.7 8671.2 8730.4 8863.1 8938.7 10719.6 10855.8 11089.6 11827.5 12096.8 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#3] mootools.html Cycles:180 Average:5603.98 Median:5152.09 stddev:3021.69 (58.6%) stddev-sans-first:3013.99 23:45:37 INFO - PROCESS | 7262 | Values: 1443.5 1467.1 1470.4 1479.7 1490.7 8040.2 8503.7 8504.4 8510.1 8513.5 7422.4 7649.1 8083.8 8289.3 8323.8 2403.9 2428.1 2431.2 2440.1 2442.1 2375.9 2393.7 2408.2 2414.1 2414.3 2379.6 2379.7 2400.5 2402.7 2403.1 2210.1 2218.7 2236.3 2244.0 2257.4 2327.3 2328.8 2362.7 2363.3 2363.4 8402.4 8492.6 8494.9 8499.4 8503.5 5135.9 5277.1 5283.7 5287.2 5288.4 3392.5 3415.1 3442.8 3453.4 3503.5 1458.9 1490.9 1514.9 1545.8 1576.2 11302.7 11447.5 11486.5 11506.8 11528.7 4494.7 4742.5 4753.2 4827.4 4849.2 9693.2 9702.2 9712.6 9717.7 9719.7 5259.2 5290.5 5314.7 5315.8 5320.9 4434.7 4450.8 4771.3 4821.2 4830.0 3956.5 3970.3 3971.6 4072.4 4077.3 1321.8 1343.2 1344.2 1357.5 1364.2 989.8 993.7 1001.2 1007.9 1016.4 5641.4 5773.1 5876.4 5919.7 5974.5 1041.2 1059.2 1069.4 1081.7 1086.1 3927.5 3952.8 4022.6 4024.5 4119.4 10053.8 10277.3 10277.8 10307.2 10358.1 10194.9 10216.9 10269.8 10307.9 10313.9 7636.7 7924.2 7924.9 8080.7 8085.9 5767.6 5883.6 5917.7 5966.5 6019.6 9406.5 9480.7 9509.6 9513.1 9513.1 9340.2 9400.5 9425.6 9556.7 9595.6 6868.2 7041.6 7304.4 7512.6 7560.6 8128.0 8313.3 8319.3 8319.3 8328.0 7321.6 7346.4 7439.7 7571.9 7602.4 7261.6 7299.9 7360.8 7367.9 7470.5 6117.1 6134.2 6137.9 6155.7 6192.2 5101.3 5142.4 5161.7 5203.7 5417.5 5185.9 5194.9 5248.9 5322.9 5404.9 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#4] prototype.html Cycles:180 Average:4717.30 Median:5326.67 stddev:1358.57 (25.5%) stddev-sans-first:1323.14 23:45:37 INFO - PROCESS | 7262 | Values: 398.2 405.4 411.1 411.4 416.5 5316.4 5385.8 5387.4 5388.4 5425.3 5209.0 5320.9 5321.0 5325.8 5327.5 4147.2 4286.0 4287.1 4289.7 4317.5 4294.8 4321.8 4361.3 4385.3 4391.8 4334.9 4335.9 4340.4 4350.2 4366.2 4014.2 4133.6 4202.9 4215.6 4385.0 3713.3 3716.3 3722.7 3723.9 3730.1 5213.2 5235.4 5237.1 5237.5 5244.2 4599.9 4765.8 4800.2 4813.2 4827.3 5594.4 5608.6 5610.7 5620.9 5657.9 4072.9 4080.1 4080.9 4084.3 4103.2 5745.7 5777.8 5807.3 5845.4 5874.7 4130.6 4132.8 4133.7 4138.3 4148.6 4179.6 4202.3 4205.2 4208.9 4212.2 4538.4 4754.9 4789.2 4797.5 4810.4 5645.6 5645.7 5648.5 5651.7 5659.6 1411.0 1419.3 1419.9 1425.6 1429.8 929.7 990.3 991.3 994.5 994.5 5121.8 5187.0 5188.5 5193.3 5202.8 5518.1 5529.7 5582.8 5675.8 5691.5 5046.4 5170.4 5180.8 5212.5 5257.8 2947.8 2961.0 2964.9 2965.3 2976.5 5655.8 5810.7 5813.1 5821.7 5869.7 5461.7 5656.5 5725.3 5726.6 5738.4 5732.5 5890.8 5916.3 5949.5 5963.8 5727.2 5728.6 5744.3 5746.9 5770.7 5460.2 5595.8 5639.5 5680.8 5693.4 5905.2 5920.3 5927.5 5971.3 5975.3 4158.9 4167.2 4171.0 4177.7 4186.3 5560.3 5698.5 5737.7 5742.5 5766.6 5557.7 5569.4 5585.7 5601.2 5643.8 5336.6 5463.8 5582.9 5608.5 5660.7 5543.7 5557.6 5574.4 5607.9 5609.7 5595.8 5608.0 5671.2 5704.1 5726.5 5288.3 5443.8 5446.5 5456.3 5513.4 23:45:37 INFO - PROCESS | 7262 | 23:45:37 INFO - PROCESS | 7262 | [#5] yui.html Cycles:180 Average:17884.92 Median:4272.03 stddev:22721.25 (531.9%) stddev-sans-first:22748.58 23:45:37 INFO - PROCESS | 7262 | Values: 755.8 765.0 781.9 878.1 896.1 15673.3 15973.3 16071.9 16207.8 16960.1 15316.9 15777.4 15994.3 16022.9 16187.9 739.7 745.6 746.3 746.5 746.7 649.7 664.3 731.4 751.1 754.7 785.7 786.3 786.6 787.1 787.5 780.0 780.4 780.5 780.6 780.9 492.5 496.6 554.9 566.4 577.8 16217.3 16220.7 16234.7 16235.0 16247.8 13986.9 14073.2 14191.7 14702.4 14932.8 12477.9 12906.4 12924.7 13062.2 13067.3 3829.5 3831.4 3832.8 3903.1 3904.4 82805.2 82922.6 83829.0 86021.1 86662.0 12047.5 12131.9 12146.8 12209.7 12224.1 23481.4 23557.2 23562.8 23567.9 23586.9 8227.0 8426.8 8529.7 8641.9 8732.3 4210.6 4222.5 4321.6 4332.5 4348.0 3584.2 3642.5 3675.8 3677.8 3678.7 625.9 628.9 632.4 633.8 660.9 756.7 762.7 766.4 770.2 791.3 9898.8 10442.6 10602.5 10969.6 11011.8 722.8 747.5 750.4 752.1 763.1 4616.4 4675.3 4998.8 5018.4 5020.6 81290.0 82614.0 82732.0 85773.0 86438.6 57437.0 61396.7 62013.0 71154.0 72377.6 62350.7 62849.0 63116.0 63596.4 63621.7 9157.3 9531.2 9542.0 9617.4 9700.7 36484.5 37091.8 37250.4 40399.2 41786.0 49561.8 49700.8 50101.0 52878.7 53813.2 13246.4 13430.5 13617.9 13689.7 13700.8 14938.0 15235.2 15268.8 15294.8 15591.5 13229.5 13364.7 13670.4 13898.9 14195.1 14041.8 14112.9 14200.4 14449.4 14525.4 13889.4 13977.7 14152.0 14188.3 14537.6 11666.7 11841.5 11997.2 12147.8 12393.0 11725.5 11746.4 11997.7 12035.5 12039.2 23:45:37 INFO - PROCESS | 7262 | -------- Summary: end -------- 23:45:37 INFO - PROCESS | 7262 | 23:45:38 INFO - TEST-INFO | 7262: exit 0 23:45:38 INFO - TEST-OK | dromaeo_css | took 1115698ms 23:45:38 INFO - TEST-START | kraken 23:45:38 INFO - operating with platform_type : linux_ 23:45:38 INFO - Initialising browser for kraken test... 23:45:38 INFO - TEST-INFO | started process 7563 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXoOJsG/profile http://localhost:45586/getInfo.html) 23:45:38 INFO - PROCESS | 7563 | 1483775138735 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 23:45:41 INFO - PROCESS | 7563 | __metrics Screen width/height:1600/1200 23:45:41 INFO - PROCESS | 7563 | colorDepth:24 23:45:41 INFO - PROCESS | 7563 | Browser inner width/height: 1024/697 23:45:41 INFO - PROCESS | 7563 | __metrics 23:45:42 INFO - PROCESS | 7563 | 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" 23:45:42 INFO - PROCESS | 7563 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:45:43 INFO - PROCESS | 7563 | console.error: PushService: 23:45:43 INFO - PROCESS | 7563 | stateChangeProcessEnqueue: Error transitioning state 23:45:43 INFO - PROCESS | 7563 | UnknownError 23:45:43 INFO - TEST-INFO | 7563: exit 0 23:45:43 INFO - Browser initialized. 23:45:43 INFO - Running cycle 1/1 for kraken test... 23:45:43 INFO - TEST-INFO | started process 7646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXoOJsG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:45:55 INFO - PROCESS | 7646 | RSS: Main: 147505152 23:45:55 INFO - PROCESS | 7646 | 23:45:58 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45586/tests/kraken/driver.html?testName=audio-beat-detection) 23:45:59 INFO - PROCESS | 7646 | RSS: Main: 202534912 23:45:59 INFO - PROCESS | 7646 | 23:46:01 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45586/tests/kraken/driver.html?testName=audio-dft) 23:46:02 INFO - PROCESS | 7646 | RSS: Main: 181481472 23:46:02 INFO - PROCESS | 7646 | 23:46:05 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45586/tests/kraken/driver.html?testName=audio-fft) 23:46:05 INFO - PROCESS | 7646 | RSS: Main: 263340032 23:46:05 INFO - PROCESS | 7646 | 23:46:07 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45586/tests/kraken/driver.html?testName=audio-oscillator) 23:46:08 INFO - PROCESS | 7646 | RSS: Main: 173891584 23:46:08 INFO - PROCESS | 7646 | 23:46:10 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45586/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:46:11 INFO - PROCESS | 7646 | RSS: Main: 178442240 23:46:11 INFO - PROCESS | 7646 | 23:46:14 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45586/tests/kraken/driver.html?testName=imaging-darkroom) 23:46:14 INFO - PROCESS | 7646 | RSS: Main: 217866240 23:46:14 INFO - PROCESS | 7646 | 23:46:18 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45586/tests/kraken/driver.html?testName=imaging-desaturate) 23:46:18 INFO - PROCESS | 7646 | RSS: Main: 217419776 23:46:18 INFO - PROCESS | 7646 | 23:46:21 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45586/tests/kraken/driver.html?testName=json-parse-financial) 23:46:22 INFO - PROCESS | 7646 | RSS: Main: 216145920 23:46:22 INFO - PROCESS | 7646 | 23:46:23 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45586/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:46:24 INFO - PROCESS | 7646 | RSS: Main: 179081216 23:46:24 INFO - PROCESS | 7646 | 23:46:26 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:46:26 INFO - PROCESS | 7646 | RSS: Main: 185430016 23:46:26 INFO - PROCESS | 7646 | 23:46:28 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:46:29 INFO - PROCESS | 7646 | RSS: Main: 194498560 23:46:29 INFO - PROCESS | 7646 | 23:46:31 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:46:32 INFO - PROCESS | 7646 | RSS: Main: 197758976 23:46:32 INFO - PROCESS | 7646 | 23:46:35 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:46:36 INFO - PROCESS | 7646 | RSS: Main: 200433664 23:46:36 INFO - PROCESS | 7646 | 23:46:37 INFO - PROCESS | 7646 | Cycle 1(1): loaded http://localhost:45586/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45586/tests/kraken/driver.html?testName=ai-astar) 23:46:37 INFO - PROCESS | 7646 | RSS: Main: 251731968 23:46:37 INFO - PROCESS | 7646 | 23:46:37 INFO - PROCESS | 7646 | __start_tp_report 23:46:37 INFO - PROCESS | 7646 | _x_x_mozilla_page_load 23:46:37 INFO - PROCESS | 7646 | _x_x_mozilla_page_load_details 23:46:37 INFO - PROCESS | 7646 | |i|pagename|runs| 23:46:37 INFO - PROCESS | 7646 | |0;ai-astar;110;112;112;111;111;111;111;110;112;111 23:46:37 INFO - PROCESS | 7646 | |1;audio-beat-detection;149;122;119;168;122;116;115;120;144;120 23:46:37 INFO - PROCESS | 7646 | |2;audio-dft;159;161;160;160;160;160;161;162;161;162 23:46:37 INFO - PROCESS | 7646 | |3;audio-fft;76;75;75;107;75;76;75;75;90;74 23:46:37 INFO - PROCESS | 7646 | |4;audio-oscillator;84;83;106;83;84;83;100;86;84;109 23:46:37 INFO - PROCESS | 7646 | |5;imaging-gaussian-blur;116;116;117;116;117;117;117;117;117;117 23:46:37 INFO - PROCESS | 7646 | |6;imaging-darkroom;148;147;147;146;147;146;147;146;146;147 23:46:37 INFO - PROCESS | 7646 | |7;imaging-desaturate;97;95;95;96;96;96;95;95;96;95 23:46:37 INFO - PROCESS | 7646 | |8;json-parse-financial;52;53;52;52;52;54;53;53;52;52 23:46:37 INFO - PROCESS | 7646 | |9;json-stringify-tinderbox;68;68;68;68;68;67;68;67;68;68 23:46:37 INFO - PROCESS | 7646 | |10;stanford-crypto-aes;87;81;86;83;82;81;81;80;80;89 23:46:38 INFO - PROCESS | 7646 | |11;stanford-crypto-ccm;126;133;135;125;125;133;125;134;202;127 23:46:38 INFO - PROCESS | 7646 | |12;stanford-crypto-pbkdf2;169;167;166;173;165;164;165;165;166;167 23:46:38 INFO - PROCESS | 7646 | |13;stanford-crypto-sha256-iterative;63;63;66;64;73;62;65;61;62;62 23:46:38 INFO - PROCESS | 7646 | __end_tp_report 23:46:38 INFO - PROCESS | 7646 | __start_cc_report 23:46:38 INFO - PROCESS | 7646 | _x_x_mozilla_cycle_collect,1590 23:46:38 INFO - PROCESS | 7646 | __end_cc_report 23:46:38 INFO - PROCESS | 7646 | __startTimestamp1483775197994__endTimestamp 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | ------- Summary: start ------- 23:46:38 INFO - PROCESS | 7646 | Number of tests: 14 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.00 stddev:0.74 (0.7%) stddev-sans-first:0.67 23:46:38 INFO - PROCESS | 7646 | Values: 110.0 112.0 112.0 111.0 111.0 111.0 111.0 110.0 112.0 111.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:122.00 stddev:17.85 (14.6%) stddev-sans-first:17.49 23:46:38 INFO - PROCESS | 7646 | Values: 149.0 122.0 119.0 168.0 122.0 116.0 115.0 120.0 144.0 120.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.97 (0.6%) stddev-sans-first:0.83 23:46:38 INFO - PROCESS | 7646 | Values: 159.0 161.0 160.0 160.0 160.0 160.0 161.0 162.0 161.0 162.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#3] audio-fft Cycles:10 Average:79.80 Median:75.00 stddev:10.65 (14.2%) stddev-sans-first:11.21 23:46:38 INFO - PROCESS | 7646 | Values: 76.0 75.0 75.0 107.0 75.0 76.0 75.0 75.0 90.0 74.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:83.50 stddev:10.48 (12.5%) stddev-sans-first:10.87 23:46:38 INFO - PROCESS | 7646 | Values: 84.0 83.0 106.0 83.0 84.0 83.0 100.0 86.0 84.0 109.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 23:46:38 INFO - PROCESS | 7646 | Values: 116.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 23:46:38 INFO - PROCESS | 7646 | Values: 148.0 147.0 147.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 23:46:38 INFO - PROCESS | 7646 | Values: 97.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 23:46:38 INFO - PROCESS | 7646 | Values: 52.0 53.0 52.0 52.0 52.0 54.0 53.0 53.0 52.0 52.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 23:46:38 INFO - PROCESS | 7646 | Values: 68.0 68.0 68.0 68.0 68.0 67.0 68.0 67.0 68.0 68.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.50 stddev:3.20 (3.9%) stddev-sans-first:3.05 23:46:38 INFO - PROCESS | 7646 | Values: 87.0 81.0 86.0 83.0 82.0 81.0 81.0 80.0 80.0 89.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:133.00 stddev:23.38 (17.6%) stddev-sans-first:24.49 23:46:38 INFO - PROCESS | 7646 | Values: 126.0 133.0 135.0 125.0 125.0 133.0 125.0 134.0 202.0 127.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.70 Median:166.50 stddev:2.63 (1.6%) stddev-sans-first:2.65 23:46:38 INFO - PROCESS | 7646 | Values: 169.0 167.0 166.0 173.0 165.0 164.0 165.0 165.0 166.0 167.0 23:46:38 INFO - PROCESS | 7646 | 23:46:38 INFO - PROCESS | 7646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:63.50 stddev:3.48 (5.5%) stddev-sans-first:3.67 23:46:38 INFO - PROCESS | 7646 | Values: 63.0 63.0 66.0 64.0 73.0 62.0 65.0 61.0 62.0 62.0 23:46:38 INFO - PROCESS | 7646 | -------- Summary: end -------- 23:46:38 INFO - PROCESS | 7646 | 23:46:39 INFO - TEST-INFO | 7646: exit 0 23:46:39 INFO - TEST-OK | kraken | took 60800ms 23:46:39 INFO - SUITE-END | took 1176s 23:46:39 INFO - Completed test suite (00:19:36) 23:46:39 INFO - javascript benchmark 23:46:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3186.6335371432283, 3215.9517607235894, 3224.274538228898, 3252.837358132093, 3271.1004473769713, 5341.893162136755, 5406.0, 5472.0423925812975, 5518.089515229983, 5550.2784637997, 5406.972965135169, 5493.478119578644, 5496.393678447981, 5499.7250137493165, 5523.889522209553, 4977.203647416414, 5008.073506401309, 5023.120953833074, 5054.21659642756, 5069.949300506984, 5036.798528058874, 5060.620453466005, 5115.9744201278945, 5128.384593848725, 5158.252053452247, 5102.464241254652, 5162.0, 5165.896682066356, 5174.172132458807, 5195.064888320101, 4889.8777530561665, 4923.384576927884, 4943.307936888838, 5013.323201367805, 5035.974820125876, 5031.773570189313, 5076.187809950427, 5078.238264260353, 5083.135866902636, 5085.389753229636, 5449.591280653967, 5540.974919639839, 5550.27846379972, 5554.833354999357, 5559.33288005442, 5709.486146246816, 5940.138679891392, 5941.8811623767715, 5945.108233764927, 5946.108083787423, 5816.331121920991, 5875.941240587625, 5930.347661757203, 5954.4938680212235, 6054.939450605481, 5570.888582228414, 5593.161025846074, 5593.188987596816, 5596.076647353182, 5599.636023658449, 6048.243969503812, 6101.206843110367, 6109.541784366191, 6112.480439162721, 6117.449429551361, 5658.151277308354, 5719.942800572025, 5723.198752174699, 5804.535637149018, 5808.128780682847, 5846.766129354778, 5871.324797648325, 5889.734961926723, 5898.793542226002, 5955.106733989849, 5704.401037890962, 5779.913301300484, 5781.421857814269, 5801.071828507418, 6020.909686354708, 5836.2412886325355, 5928.881422371528, 5931.40685931412, 5940.31686356066, 5981.7607295707685, 4577.22187228172, 4579.450465944042, 4582.977085114553, 4585.701929374647, 4622.168009758208, 4010.47863777707, 4031.133306339151, 4050.1494686115657, 4080.510338759368, 4121.29937910556, 5502.972485137549, 5557.110862262019, 5564.610477266552, 5597.160425936142, 5601.719914004283, 5925.34821169671, 5933.3473317934995, 5937.2578427696535, 5938.0, 5940.168376427304, 5324.946750532445, 5363.249145119687, 5544.528715059185, 5550.833474995757, 5553.555715542747, 4994.425641051253, 4998.500149984972, 5055.646104772704, 5070.670406423645, 5128.102582048126, 6108.633481991094, 6165.630062196283, 6166.81499555014, 6184.690765461709, 6190.783322583688, 5815.3603103657715, 5910.054391297372, 5933.851653708692, 5938.138969849261, 5994.910076348771, 5897.587168898136, 5911.083782013795, 6059.0608455689435, 6152.077188421773, 6169.722362493789, 5737.311522617312, 5878.294604647299, 5885.058390657474, 5917.5857689961285, 5920.437558431942, 5954.07711804671, 5964.403559644001, 5967.194428752063, 5967.254093238345, 5971.193888824953, 5559.44405559457, 5630.85922851932, 5746.051901436216, 5794.681292528951, 5803.593748437737, 5707.6290041147195, 5766.106253530709, 5766.365699773106, 5838.5621078418435, 5845.707714614167, 5520.696361700145, 5803.622764520293, 5809.912851307149, 5815.0, 5819.5344372450945, 5682.886342273048, 5731.627444216113, 5780.826575202751, 5832.504237139721, 5836.737346819318, 5514.889702206014, 5579.916301255404, 5604.271444712162, 5722.0, 5723.427657234243], "value": 5449.800003114562, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.3689548985935, 573.3521121133119, 590.0176206615982, 604.4650484321375, 615.1725928625997, 34381.48427773582, 43633.78183109088, 44717.10565788687, 44864.3270350945, 44869.551304486944, 46488.30267545981, 46997.76501117498, 47048.76475617617, 47095.76452117743, 47115.0, 8583.098774628668, 8927.642894284238, 9114.270858331318, 9129.452232866017, 9131.908680913173, 8415.368847336444, 10677.733056673567, 10877.945610271978, 10896.18278629102, 11273.37996410197, 10793.352398856054, 10829.0, 10837.729056773565, 10841.349519028845, 10868.07621352186, 10826.512806923707, 10832.458377081115, 10870.184736144742, 10892.346459212473, 10938.398388088612, 10546.947265263625, 10578.57685692579, 10603.522841472151, 10610.469476526143, 10613.097886679641, 43002.35496467556, 43039.0, 43042.569574304485, 43150.56849431497, 43160.56839431597, 4233.978830105861, 4268.253055715238, 4324.437823083011, 4400.691951563392, 4427.380166776654, 32757.0, 32809.50785738216, 33743.15642108942, 34203.65796342055, 34776.30447391063, 4243.702940794145, 4286.142771445723, 4327.415798867144, 4363.258246098171, 4452.109578084396, 343452.0, 343630.0, 343698.0, 344509.0, 345305.9999999975, 60719.392806072254, 61194.0820887687, 61211.38788612146, 61237.0, 61376.0, 63329.68335158295, 63349.0, 63434.68282658557, 63440.68279658572, 63583.36416635867, 15809.525714228592, 16349.591260218589, 16350.75473867893, 16370.345186192419, 16385.262663180187, 11874.990625796903, 11923.105767067505, 12072.939635301767, 12236.81644775329, 12455.564055258023, 15970.843936068715, 16448.917755411145, 16624.418145364853, 16626.66986641066, 16653.0, 6300.9684951574945, 6329.3354197809485, 6436.613803171825, 6525.967370163119, 6566.704498297588, 932.3659911260409, 935.2050756856618, 936.2135805922921, 954.9761255968517, 957.1816097236901, 30224.546631800542, 32473.35053298968, 32500.34999300048, 32724.345513089604, 33086.66913330836, 1040.953157107932, 1104.8232282834708, 1105.8838821923744, 1113.0817075912405, 1114.8940850619178, 17079.231434585472, 17098.743518847226, 17292.827071729374, 17309.480715578553, 17361.3923512679, 488471.0, 489222.0, 489247.0, 490882.0, 492106.0, 63178.0, 63531.682341588, 63665.681671591345, 63735.68132159402, 63784.68107659525, 1443.8050863133553, 1461.554225961092, 1467.5450610310838, 1504.277946585645, 1515.113658509784, 30013.849930750206, 31730.365392692478, 32060.358792823547, 32211.35577288488, 32385.51421728723, 51333.743331283105, 52867.735661322986, 52886.0, 52891.206631901325, 52959.470405295455, 379675.0, 381687.0, 381931.0, 382640.0, 383276.0, 36039.27921441609, 36943.81528092342, 39936.40095398513, 40694.79652601718, 41884.371734423396, 48881.26678099902, 49343.50656493533, 49743.0, 50621.49378506168, 50794.74602626963, 40165.79917100396, 40911.59088409078, 41458.79270603628, 41740.0, 43243.0, 42137.36793948032, 42347.0, 42596.0, 42835.571644284406, 43098.0, 40901.38647920343, 41456.1708765829, 41960.58039419567, 42040.579594203664, 42075.0, 34342.82828585841, 35726.0, 36190.27619447544, 36418.4537231947, 37065.0, 27349.589756154073, 27431.0, 27641.308967275978, 27682.446351072464, 27789.305267368476], "value": 20730.459458902507, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.3428818421156, 427.09242858924785, 437.48376915240016, 437.66081287002567, 447.1571088498182, 19238.615227695456, 19879.900600496996, 20927.0, 21108.0, 21353.893230533846, 19495.512612184666, 20486.2829800957, 20842.166313347487, 21262.681059784114, 21554.56890862182, 5787.565932555054, 5983.58114931955, 6005.069214271794, 6020.277566692003, 6021.307549631782, 5796.594238403313, 5874.206982057411, 5913.645181289115, 5921.542539988294, 5923.289205295349, 5864.941350586503, 5866.237389139406, 5875.88248235035, 5878.353381128072, 5881.294244690643, 5872.236609240793, 5961.493273071794, 6202.3487533808975, 6233.657148856811, 6336.144620476222, 6044.455998960115, 6094.664793436359, 6154.7230374633245, 6180.87638247237, 6183.907241391383, 14395.712085758227, 14561.271936403138, 14631.561053168421, 14701.0, 14702.63243418923, 2545.9363515912064, 2680.772134368581, 2825.9152225433268, 3037.2862377341307, 3065.6167979002626, 10725.463726813628, 10970.122390208764, 10973.615923442641, 10982.615608453665, 11009.61466348682, 1904.7999960004138, 1982.2764690887866, 2019.2730616977876, 2026.7466566679166, 2061.4537147655883, 89382.5530872348, 90913.0, 92380.0, 93879.53060234655, 96780.51609741907, 11597.884021159849, 11694.47374868116, 11730.589429369931, 11895.762084758257, 12112.81830772539, 16672.416465423652, 16688.83311166897, 16693.916530417515, 16697.499075027525, 16720.41478548245, 8345.582720863933, 8509.702160424355, 8512.361572881935, 8735.475871447607, 8795.252403545643, 7944.721934732257, 8015.238552337519, 8195.180481951757, 8228.50628962264, 8254.380921430797, 4981.352424184905, 5062.620303477254, 5085.4914508548845, 5104.923426148611, 5157.200633901752, 2055.2498338106484, 2063.329417939146, 2118.671605901088, 2132.285684295747, 2134.4343748906554, 939.5818860607101, 951.3816019587324, 973.7176218896583, 975.9951200244095, 980.3578655980344, 18922.432327030212, 20801.0, 20900.790992089886, 20950.790492094882, 21208.15167393318, 956.7464621875208, 975.8341082016075, 978.2369751593768, 982.5529384130347, 991.1327588360185, 10889.401082940356, 11747.17769756126, 11844.585439509576, 11847.0, 11850.525978960917, 584974.0, 617756.0, 662417.0, 663465.0, 684956.0, 63166.684166578874, 67380.0, 100169.0, 106229.0, 109413.0, 992.275638783692, 1016.9389836609679, 1066.5253961986996, 1068.417712346773, 1072.7050061233224, 18434.631307374046, 18472.722909156106, 18690.915998860037, 18708.906455467637, 19295.90352048231, 45024.54975450203, 45424.0, 45539.0, 45831.770841146914, 45894.050660120956, 62362.0, 63289.36710632835, 63705.36294636994, 65017.0, 65022.34977650163, 5936.525077993664, 6872.862542749217, 8670.132986701532, 8786.472811631322, 9037.593308301011, 12361.93819030899, 13153.93423032917, 13330.333483325601, 13690.726185476036, 13810.792838107638, 11816.76366472683, 11817.231879927776, 11867.228630139014, 12051.276923384625, 12422.06834487399, 11672.358020308964, 11685.883141168819, 11919.523219071487, 12019.338936358234, 12510.937445312715, 11820.40897955116, 11893.76212475763, 12189.0, 12211.633650990485, 12329.568465103679, 8495.702650407453, 8671.21959023691, 8730.388872778845, 8863.069377715505, 8938.68714594986, 10719.624813131502, 10855.837162442413, 11089.5564177432, 11827.467763950472, 12096.818547721616], "value": 9602.41025227821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.5236371997241, 1467.1417220925757, 1470.3530446603493, 1479.726250643631, 1490.7242160200365, 8040.195980401957, 8503.74488765338, 8504.36217283703, 8510.148985101487, 8513.489190648557, 7422.36909862661, 7649.08211014679, 8083.838323233532, 8289.295409890166, 8323.791905202374, 2403.8677872716994, 2428.077330614373, 2431.234161239204, 2440.0971640493044, 2442.0842184180933, 2375.8574485530835, 2393.7247216570145, 2408.2414039577475, 2414.106780491221, 2414.3481260059775, 2379.6192609182535, 2379.726331471877, 2400.459896523286, 2402.7116745990506, 2403.146882856578, 2210.082815631511, 2218.7004754358118, 2236.284388995514, 2243.9663405048937, 2257.3905045637584, 2327.2552783109413, 2328.7554806745216, 2362.70466191726, 2363.2673871100014, 2363.3618922890723, 8402.411831171821, 8492.575371231414, 8494.872576911352, 8499.36254780894, 8503.48979061252, 5135.922961155586, 5277.076511610453, 5283.709395983219, 5287.154055351164, 5288.444713305086, 3392.4741665041956, 3415.077928959167, 3442.8106454145013, 3453.3783918894587, 3503.5095086687884, 1458.8541145885326, 1490.9403623855142, 1514.8788096952217, 1545.768134779781, 1576.2118940529735, 11302.660920172408, 11447.542098316057, 11486.540538378455, 11506.76986460266, 11528.654140375802, 4494.696398147359, 4742.549457801503, 4753.168195565762, 4827.42070951488, 4849.157320218294, 9693.176080033276, 9702.223822094216, 9712.562934667956, 9717.6598819041, 9719.708408747749, 5259.184826351921, 5290.49740274681, 5314.707691077027, 5315.840524784263, 5320.866978325573, 4434.667399945017, 4450.799714012813, 4771.33201351811, 4821.228603423436, 4829.975850120727, 3956.5054368203973, 3970.3250447424007, 3971.5631280559114, 4072.429859819628, 4077.26609210345, 1321.7752981993085, 1343.1806597975228, 1344.205485676613, 1357.5384369314481, 1364.1746743220408, 989.8218320702199, 993.6969224386631, 1001.1540248490014, 1007.9143272821746, 1016.4104819204844, 5641.379448260688, 5773.1051686988585, 5876.382979787147, 5919.67441790697, 5974.492168165754, 1041.156663102889, 1059.1791361694595, 1069.395791377869, 1081.7187531241934, 1086.0876863434594, 3927.4501569780255, 3952.822123004472, 4022.6379625833815, 4024.5170579530645, 4119.402686610425, 10053.798924021625, 10277.331973421704, 10277.845832312521, 10307.175425965905, 10358.067773900235, 10194.898051019543, 10216.948915255376, 10269.794604107876, 10307.896921030844, 10313.896861031444, 7636.656350464243, 7924.207579242145, 7924.920750792418, 8080.676772929134, 8085.878711819328, 5767.625104368202, 5883.588148829541, 5917.6745279010065, 5966.49284810796, 6019.608725432833, 9406.529673516434, 9480.668176613786, 9509.572069256761, 9513.143817056498, 9513.143817056498, 9340.206082483064, 9400.482973436254, 9425.57584908667, 9556.665516706882, 9595.616175353187, 6868.244493105654, 7041.647917604202, 7304.3791277739865, 7512.624368781429, 7560.6219689016425, 8128.0, 8313.251807337369, 8319.251267385964, 8319.334453243848, 8328.042275138436, 7321.560706357635, 7346.375558077623, 7439.739609113872, 7571.886421703569, 7602.429817763579, 7261.636918154177, 7299.927000729925, 7360.77917662471, 7367.926320736724, 7470.4770666052855, 6117.082437634391, 6134.171886795226, 6137.877242455036, 6155.72299246526, 6192.195014648067, 5101.311322971352, 5142.434332223377, 5161.741912904265, 5203.661761985459, 5417.51242388187, 5185.948140518546, 5194.948050519598, 5248.89502209946, 5322.866928326823, 5404.918926216189], "value": 4600.574860958526, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.2214770124405, 405.37572138906086, 411.116100384174, 411.43016921563634, 416.5147603042455, 5316.3620365556135, 5385.7576409061585, 5387.353517577886, 5388.353397592285, 5425.321834770653, 5209.0, 5320.893582128355, 5320.998261634071, 5325.840224793253, 5327.493888080636, 4147.2327619390435, 4286.0, 4287.078278170193, 4289.742615443083, 4317.481902171744, 4294.785260736966, 4321.762303073329, 4361.280388735855, 4385.298352263639, 4391.802368893392, 4334.869953901388, 4335.875421043682, 4340.392345071693, 4350.173467041268, 4366.1922544329245, 4014.1770936958, 4133.565975572582, 4202.936955945633, 4215.620594146541, 4385.0, 3713.3316003119426, 3716.2939041582013, 3722.7394082414244, 3723.8510459581585, 3730.123420996064, 5213.218017297436, 5235.424103348629, 5237.083510385705, 5237.502437268491, 5244.213367994793, 4599.931001034987, 4765.833195838129, 4800.183968725325, 4813.243166244296, 4827.25177597473, 5594.356648985337, 5608.551315894719, 5610.747516361774, 5620.9437905620825, 5657.886842263131, 4072.8981775455554, 4080.12277360366, 4080.9387859182134, 4084.305668036441, 4103.158852435244, 5745.6552606843725, 5777.7977770777825, 5807.274090738658, 5845.444682755174, 5874.676892770896, 4130.607592278729, 4132.834686612502, 4133.731307465006, 4138.296489596776, 4148.647364973951, 4179.623833854967, 4202.285611446062, 4205.17998990194, 4208.936865947013, 4212.178625168124, 4538.364628951949, 4754.857354279377, 4789.185838407479, 4797.496262892416, 4810.374651295379, 5645.604807663506, 5645.673266782303, 5648.548116150698, 5651.745671444794, 5659.603827732101, 1410.9840914541514, 1419.2619837684347, 1419.9077059991068, 1425.5580769961346, 1429.8498657640798, 929.693201243588, 990.3265779270165, 991.2862738828032, 994.4679596415883, 994.5126887824914, 5121.77077501397, 5186.974065129651, 5188.45521220274, 5193.324867767166, 5202.76587553561, 5518.061929471999, 5529.723513824292, 5582.804601838916, 5675.77296908119, 5691.544676425876, 5046.44489106198, 5170.353705786777, 5180.766865491062, 5212.514433386543, 5257.8159764408065, 2947.8083924544835, 2960.9703902961123, 2964.9110526684235, 2965.303153758865, 2976.538636511344, 5655.830325090254, 5810.738516766755, 5813.12803079533, 5821.7380217890295, 5869.706514674249, 5461.672299662033, 5656.490915817596, 5725.2843394575675, 5726.625609853689, 5738.368779434259, 5732.512736417368, 5890.764369425261, 5916.290045194518, 5949.524038076857, 5963.821085367533, 5727.198192253171, 5728.598998070095, 5744.339400968942, 5746.879358525048, 5770.711464426846, 5460.180972854104, 5595.776168953196, 5639.520640745419, 5680.829575112753, 5693.405846363025, 5905.202797622266, 5920.2895652521975, 5927.4665280125, 5971.28344598651, 5975.312839023568, 4158.854440094582, 4167.166566686736, 4170.958290417057, 4177.68667349956, 4186.267403204488, 5560.304961879765, 5698.487136157766, 5737.655740655574, 5742.454466825645, 5766.596338256281, 5557.666160121623, 5569.354556585589, 5585.748641311069, 5601.151619965321, 5643.830685079454, 5336.573074154136, 5463.754131064032, 5582.888342233051, 5608.46719561641, 5660.660360378226, 5543.72281385921, 5557.637629234255, 5574.358948720786, 5607.943920560742, 5609.747561359667, 5595.76893083521, 5607.990561698934, 5671.234383358359, 5704.1443783432815, 5726.541876649775, 5288.338957630296, 5443.755031023693, 5446.50981411659, 5456.29913192972, 5513.365962914156], "value": 4303.381827521398, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.8412733326002, 764.9847003059942, 781.8749000159972, 878.1306506558507, 896.1397058823529, 15673.294701738421, 15973.281202345908, 16071.919640401782, 16207.83792162081, 16960.067196304197, 15316.923415382907, 15777.447789327376, 15994.280257388416, 16022.919885400557, 16187.91906040471, 739.6782399656154, 745.6458182363361, 746.264929044893, 746.5483382553558, 746.7199800074972, 649.6524359467686, 664.2560332427688, 731.3965978068088, 751.0874287740394, 754.6679461037154, 785.7485604606528, 786.2766255045343, 786.6106277392698, 787.1262898183012, 787.4763282417185, 779.9961000194962, 780.4185881518248, 780.5394817057935, 780.5745868501651, 780.8945792318021, 492.477973348252, 496.57294726535514, 554.8917960997607, 566.3713278261126, 577.8208755285875, 16217.270222840001, 16220.675586488322, 16234.675306493922, 16235.0, 16247.83752162487, 13986.930065349608, 14073.155610663393, 14191.71616567663, 14702.411903523845, 14932.776008359884, 12477.875221247763, 12906.354682265943, 12924.676883077998, 13062.150960187557, 13067.346632668328, 3829.48301979232, 3831.4252862070634, 3832.770033797981, 3903.0632648164237, 3904.4338570907344, 82805.17194828035, 82922.58538707328, 83829.0, 86021.13978860257, 86662.0, 12047.457864396123, 12131.939340303243, 12146.757064858653, 12209.69475763113, 12224.1443098984, 23481.41296467602, 23557.175498857458, 23562.758703433203, 23567.882160589088, 23586.88206558956, 8226.958865205635, 8426.789330266793, 8529.701460448854, 8641.913580864111, 8732.257758090507, 4210.621044106045, 4222.451081359465, 4321.611055005064, 4332.545082766327, 4347.999960009216, 3584.175639602906, 3642.5082613846794, 3675.8162091895297, 3677.786330510925, 3678.70570354371, 625.8623102917351, 628.9339619339903, 632.3644737039338, 633.7718421368396, 660.9372109649576, 756.6632848382417, 762.7254188492138, 766.3677466090386, 770.2451597434433, 791.2799352589117, 9898.802023959624, 10442.582296708199, 10602.469876506144, 10969.616063437901, 11011.779764404668, 722.8156820010969, 747.517850986111, 750.3873206321834, 752.0749478141917, 763.1414658509177, 4616.3998680172035, 4675.275332323496, 4998.750062496861, 5018.397792264878, 5020.623453241022, 81290.0, 82614.0, 82732.0, 85773.0, 86438.56780716182, 57437.0, 61396.693016534635, 62013.0, 71154.0, 72377.63811180911, 62350.68824655938, 62849.0, 63116.0, 63596.36403635997, 63621.68189159025, 9157.267418606629, 9531.189848862925, 9542.0, 9617.422954622465, 9700.708978730649, 36484.45273320849, 37091.814540927124, 37250.44124338083, 40399.1920161601, 41786.0, 49561.75219123881, 49700.75149624229, 50101.0, 52878.73560632172, 53813.203930026924, 13246.403911824184, 13430.529931452353, 13617.931910340385, 13689.726205475636, 13700.794488082487, 14938.0, 15235.238238088274, 15268.770968435705, 15294.847051529787, 15591.532254032398, 13229.470821167046, 13364.732705345645, 13670.384832682354, 13898.930505347409, 14195.148291102567, 14041.789373159616, 14112.929435352757, 14200.360983755549, 14449.422023118957, 14525.418983240974, 13889.444422222998, 13977.650558736113, 14152.0, 14188.290585470892, 14537.563873083824, 11666.70833229176, 11841.46713397882, 11997.220180688228, 12147.817782733442, 12393.008559315052, 11725.530978760755, 11746.434421317676, 11997.700057498283, 12035.518579257083, 12039.157258991787], "value": 7326.312888400283, "unit": "score"}], "name": "dromaeo_css", "value": 7347.740933467625, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 112.0, 111.0, 111.0, 111.0, 111.0, 110.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 119.0, 168.0, 122.0, 116.0, 115.0, 120.0, 144.0, 120.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 160.0, 160.0, 160.0, 161.0, 162.0, 161.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 107.0, 75.0, 76.0, 75.0, 75.0, 90.0, 74.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 106.0, 83.0, 84.0, 83.0, 100.0, 86.0, 84.0, 109.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 52.0, 54.0, 53.0, 53.0, 52.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 68.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 81.0, 86.0, 83.0, 82.0, 81.0, 81.0, 80.0, 80.0, 89.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 135.0, 125.0, 125.0, 133.0, 125.0, 134.0, 202.0, 127.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 166.0, 173.0, 165.0, 164.0, 165.0, 165.0, 166.0, 167.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 66.0, 64.0, 73.0, 62.0, 65.0, 61.0, 62.0, 62.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1500.8000000000002, "alertThreshold": 2.0}]} 23:46:39 INFO - Return code: 0 23:46:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:46:39 INFO - # TBPL SUCCESS # 23:46:39 INFO - Running post-action listener: _resource_record_post_action 23:46:39 INFO - [mozharness: 2017-01-07 07:46:39.311848Z] Finished run-tests step (success) 23:46:39 INFO - Running post-run listener: _resource_record_post_run 23:46:39 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 411865088; Read time: 0; Write time: 259772 23:46:39 INFO - TinderboxPrint: CPU usage
13.1% 23:46:39 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 23:46:39 INFO - TinderboxPrint: I/O write bytes / time
411,865,088 / 259,772 23:46:39 INFO - TinderboxPrint: CPU idle
8,283.1 (86.9%) 23:46:39 INFO - TinderboxPrint: CPU user
1,200.5 (12.6%) 23:46:39 INFO - TinderboxPrint: Swap in / out
0 / 0 23:46:39 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116748288; Read time: 0; Write time: 72156 23:46:39 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 295116800; Read time: 0; Write time: 187616 23:46:39 INFO - Running post-run listener: _upload_blobber_files 23:46:39 INFO - Blob upload gear active. 23:46:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:46:39 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 23:46:39 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'] 23:46:39 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 23:46:40 INFO - (blobuploader) - INFO - Open directory for files ... 23:46:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:46:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:46:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:46:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:46:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:46:41 INFO - (blobuploader) - INFO - Done attempting. 23:46:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:46:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:46:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:46:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:46:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:46:42 INFO - (blobuploader) - INFO - Done attempting. 23:46:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:46:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:46:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:46:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:46:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:46:45 INFO - (blobuploader) - INFO - Done attempting. 23:46:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:46:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:46:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:46:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:46:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:46:46 INFO - (blobuploader) - INFO - Done attempting. 23:46:46 INFO - (blobuploader) - INFO - Iteration through files over. 23:46:46 INFO - Return code: 0 23:46:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:46:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:46:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f748a970200adcef546e72f612541a86a412eb5a8f5b0a6c161f3f5f0a1d4b3091540622ff6dbf6e4ea85723b12586cb6cc4c5e13f6cf08db8510fdfa133060", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/743af1e549a7dfa0ef9bf11db9684343c2715d33dd09989912d1ec464b97025d0337f28a30331a9aa84872e284d118122d5251f98a96687edba57ebe803e246d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6271e17a05a7420daadc550feff5967620048a3f5ec21b345fd38f1b403066b18002833997f41b8d66d80cfc5f456d3f7a1389d25e4b2d699f7affce9d0b3865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e595ed8f67323d910bcff156b1fa9fc554c0ad7ade67bb2698de9d94eefc08cd2cf3059d841c9677bcca0ab90f01b760426bf785d9d3843e81717cfb53a0d281"} 23:46:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:46:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:46:46 INFO - Contents: 23:46:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f748a970200adcef546e72f612541a86a412eb5a8f5b0a6c161f3f5f0a1d4b3091540622ff6dbf6e4ea85723b12586cb6cc4c5e13f6cf08db8510fdfa133060", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/743af1e549a7dfa0ef9bf11db9684343c2715d33dd09989912d1ec464b97025d0337f28a30331a9aa84872e284d118122d5251f98a96687edba57ebe803e246d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6271e17a05a7420daadc550feff5967620048a3f5ec21b345fd38f1b403066b18002833997f41b8d66d80cfc5f456d3f7a1389d25e4b2d699f7affce9d0b3865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e595ed8f67323d910bcff156b1fa9fc554c0ad7ade67bb2698de9d94eefc08cd2cf3059d841c9677bcca0ab90f01b760426bf785d9d3843e81717cfb53a0d281"} 23:46:46 INFO - Running post-run listener: copy_logs_to_upload_dir 23:46:46 INFO - Copying logs to upload dir... 23:46:46 INFO - mkdir: /builds/slave/test/build/upload/logs 23:46:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.444791 ========= master_lag: 1.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-01-06 23:46:47.387522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-01-06 23:46:47.391937) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 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/9f748a970200adcef546e72f612541a86a412eb5a8f5b0a6c161f3f5f0a1d4b3091540622ff6dbf6e4ea85723b12586cb6cc4c5e13f6cf08db8510fdfa133060", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/743af1e549a7dfa0ef9bf11db9684343c2715d33dd09989912d1ec464b97025d0337f28a30331a9aa84872e284d118122d5251f98a96687edba57ebe803e246d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6271e17a05a7420daadc550feff5967620048a3f5ec21b345fd38f1b403066b18002833997f41b8d66d80cfc5f456d3f7a1389d25e4b2d699f7affce9d0b3865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e595ed8f67323d910bcff156b1fa9fc554c0ad7ade67bb2698de9d94eefc08cd2cf3059d841c9677bcca0ab90f01b760426bf785d9d3843e81717cfb53a0d281"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483767014/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/9f748a970200adcef546e72f612541a86a412eb5a8f5b0a6c161f3f5f0a1d4b3091540622ff6dbf6e4ea85723b12586cb6cc4c5e13f6cf08db8510fdfa133060", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/743af1e549a7dfa0ef9bf11db9684343c2715d33dd09989912d1ec464b97025d0337f28a30331a9aa84872e284d118122d5251f98a96687edba57ebe803e246d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6271e17a05a7420daadc550feff5967620048a3f5ec21b345fd38f1b403066b18002833997f41b8d66d80cfc5f456d3f7a1389d25e4b2d699f7affce9d0b3865", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e595ed8f67323d910bcff156b1fa9fc554c0ad7ade67bb2698de9d94eefc08cd2cf3059d841c9677bcca0ab90f01b760426bf785d9d3843e81717cfb53a0d281"}' ========= master_lag: 1.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-01-06 23:46:49.098074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:46:49.101477) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1483755082.24961-1005065885 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004586 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-06 23:46:49.118540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 23:46:49.118867) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-06 23:46:49.119168) ========= ========= Total master_lag: 3.12 =========