builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1484638946.2 results: success (0) buildid: 20170116234152 builduid: 46b8910b68d443a1a039366770215802 revision: 14a12ac05a4b9dddeb6919540ab05af08e730912 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.203739) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.204195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.204476) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.219101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.219374) ========= 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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-16 23:42:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 87.9M=0s 2017-01-16 23:42:26 (87.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114592 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.347609) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.347936) ========= 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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016382 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:26.397822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 23:42:26.398197) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 14a12ac05a4b9dddeb6919540ab05af08e730912 --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 14a12ac05a4b9dddeb6919540ab05af08e730912 --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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-16 23:42:26,583 truncating revision to first 12 chars 2017-01-16 23:42:26,583 Setting DEBUG logging. 2017-01-16 23:42:26,583 attempt 1/10 2017-01-16 23:42:26,583 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/14a12ac05a4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-16 23:42:27,646 unpacking tar archive at: mozilla-inbound-14a12ac05a4b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.416547 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-16 23:42:27.828317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:27.828674) ========= script_repo_revision: 14a12ac05a4b9dddeb6919540ab05af08e730912 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:27.829083) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:27.829371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-16 23:42:27.838118) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-01-16 23:42:27.838393) ========= /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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:42:28 INFO - MultiFileLogger online at 20170116 23:42:28 in /builds/slave/test 23:42:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 23:42:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:42:28 INFO - {'append_to_log': False, 23:42:28 INFO - 'base_work_dir': '/builds/slave/test', 23:42:28 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 23:42:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:42:28 INFO - 'branch': 'Mozilla-Inbound', 23:42:28 INFO - 'buildbot_json_path': 'buildprops.json', 23:42:28 INFO - 'config_files': ('talos/linux_config.py',), 23:42:28 INFO - 'default_actions': ('clobber', 23:42:28 INFO - 'read-buildbot-config', 23:42:28 INFO - 'download-and-extract', 23:42:28 INFO - 'populate-webroot', 23:42:28 INFO - 'create-virtualenv', 23:42:28 INFO - 'install', 23:42:28 INFO - 'run-tests'), 23:42:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:42:28 INFO - 'download_minidump_stackwalk': True, 23:42:28 INFO - 'download_symbols': 'ondemand', 23:42:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:42:28 INFO - 'tooltool.py': '/tools/tooltool.py', 23:42:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:42:28 INFO - '/tools/misc-python/virtualenv.py')}, 23:42:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:42:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:42:28 INFO - 'installer_path': 'installer.exe', 23:42:28 INFO - 'log_level': 'info', 23:42:28 INFO - 'log_name': 'talos', 23:42:28 INFO - 'log_to_console': True, 23:42:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:42:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:42:28 INFO - 'opt_config_files': (), 23:42:28 INFO - 'pip_index': False, 23:42:28 INFO - 'sps_profile': False, 23:42:28 INFO - 'sps_profile_interval': 0, 23:42:28 INFO - 'suite': 'dromaeojs', 23:42:28 INFO - 'system_bits': '32', 23:42:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:42:28 INFO - 'title': 'talos-linux64-ix-002', 23:42:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:42:28 INFO - 'use_talos_json': True, 23:42:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:42:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:42:28 INFO - 'work_dir': 'build'} 23:42:28 INFO - [mozharness: 2017-01-17 07:42:28.131930Z] Running clobber step. 23:42:28 INFO - Running pre-action listener: _resource_record_pre_action 23:42:28 INFO - Running main action method: clobber 23:42:28 INFO - rmtree: /builds/slave/test/build 23:42:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:42:29 INFO - Running post-action listener: _resource_record_post_action 23:42:29 INFO - [mozharness: 2017-01-17 07:42:29.206590Z] Finished clobber step (success) 23:42:29 INFO - [mozharness: 2017-01-17 07:42:29.206690Z] Running read-buildbot-config step. 23:42:29 INFO - Running pre-action listener: _resource_record_pre_action 23:42:29 INFO - Running main action method: read_buildbot_config 23:42:29 INFO - Using buildbot properties: 23:42:29 INFO - { 23:42:29 INFO - "project": "", 23:42:29 INFO - "product": "firefox", 23:42:29 INFO - "script_repo_revision": "production", 23:42:29 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 23:42:29 INFO - "repository": "", 23:42:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 23:42:29 INFO - "buildid": "20170116234152", 23:42:29 INFO - "stage_platform": "linux64-pgo", 23:42:29 INFO - "basedir": "/builds/slave/test", 23:42:29 INFO - "buildnumber": 2455, 23:42:29 INFO - "platform": "ubuntu64_hw", 23:42:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:42:29 INFO - "slavebuilddir": "test", 23:42:29 INFO - "branch": "mozilla-inbound", 23:42:29 INFO - "revision": "14a12ac05a4b9dddeb6919540ab05af08e730912", 23:42:29 INFO - "slavename": "talos-linux64-ix-002", 23:42:29 INFO - "builduid": "46b8910b68d443a1a039366770215802", 23:42:29 INFO - "repo_path": "integration/mozilla-inbound" 23:42:29 INFO - } 23:42:29 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:42:29 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 23:42:29 INFO - Running post-action listener: _resource_record_post_action 23:42:29 INFO - [mozharness: 2017-01-17 07:42:29.209074Z] Finished read-buildbot-config step (success) 23:42:29 INFO - [mozharness: 2017-01-17 07:42:29.209165Z] Running download-and-extract step. 23:42:29 INFO - Running pre-action listener: _resource_record_pre_action 23:42:29 INFO - Running main action method: download_and_extract 23:42:29 INFO - mkdir: /builds/slave/test/build/tests 23:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:29 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:42:29 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/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:42:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/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:42:29 INFO - Downloaded 1361 bytes. 23:42:29 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:42:29 INFO - Using the following test package requirements: 23:42:29 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:42:29 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:42:29 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:42:29 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'jsshell-linux-x86_64.zip'], 23:42:29 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:42:29 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:42:29 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:42:29 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:42:29 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:42:29 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:42:29 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:42:29 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:42:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:42:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:42:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:42:29 INFO - Expected file size: 21864971 23:42:29 INFO - Obtained file size: 21864971 23:42:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:42:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:42:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:42:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:42:33 INFO - Expected file size: 21864971 23:42:33 INFO - Obtained file size: 21864971 23:42:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:42:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:42:38 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:42:38 INFO - Expected file size: 14403463 23:42:38 INFO - Obtained file size: 14403463 23:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:38 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:38 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/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:42:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/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:42:39 INFO - Downloaded 61768503 bytes. 23:42:39 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:39 INFO - mkdir: /builds/slave/test/properties 23:42:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:42:39 INFO - Writing to file /builds/slave/test/properties/build_url 23:42:39 INFO - Contents: 23:42:39 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:42:39 INFO - Running post-action listener: _resource_record_post_action 23:42:39 INFO - Running post-action listener: set_extra_try_arguments 23:42:39 INFO - [mozharness: 2017-01-17 07:42:39.830154Z] Finished download-and-extract step (success) 23:42:39 INFO - [mozharness: 2017-01-17 07:42:39.830384Z] Running populate-webroot step. 23:42:39 INFO - Running pre-action listener: _resource_record_pre_action 23:42:39 INFO - Running main action method: populate_webroot 23:42:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:42:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:42:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:42:39 INFO - '--tppagecycles', 23:42:39 INFO - '7'], 23:42:39 INFO - 'tests': ['tsvgm']}}, 23:42:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:42:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:42:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:42:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:42:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:42:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['damp', 'tps']}, 23:42:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'tests': ['damp', 'tps']}, 23:42:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['dromaeo_dom']}, 23:42:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:42:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['basic_compositor_video']}, 23:42:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:42:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'other-e10s': {'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['a11yr', 23:42:39 INFO - 'ts_paint', 23:42:39 INFO - 'tpaint', 23:42:39 INFO - 'sessionrestore', 23:42:39 INFO - 'sessionrestore_no_auto_restore', 23:42:39 INFO - 'tabpaint']}, 23:42:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['tsvgx', 23:42:39 INFO - 'tsvgr_opacity', 23:42:39 INFO - 'tart', 23:42:39 INFO - 'tscrollx', 23:42:39 INFO - 'cart', 23:42:39 INFO - 'tsvg_static']}, 23:42:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:42:39 INFO - 'tsvgr_opacity', 23:42:39 INFO - 'tart', 23:42:39 INFO - 'tscrollx', 23:42:39 INFO - 'cart', 23:42:39 INFO - 'tsvg_static']}, 23:42:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'talos_options': ['--disable-e10s'], 23:42:39 INFO - 'tests': ['tp5o']}, 23:42:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:42:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'talos_options': ['--disable-e10s', 23:42:39 INFO - '--xperf_path', 23:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:39 INFO - 'tests': ['tp5n']}, 23:42:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:42:39 INFO - 'talos_options': ['--xperf_path', 23:42:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:42:39 INFO - 'tests': ['tp5n']}}, 23:42:39 INFO - 'talos.zip': {'path': '', 23:42:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:42:39 INFO - Running post-action listener: _resource_record_post_action 23:42:39 INFO - [mozharness: 2017-01-17 07:42:39.850544Z] Finished populate-webroot step (success) 23:42:39 INFO - [mozharness: 2017-01-17 07:42:39.850634Z] Running create-virtualenv step. 23:42:39 INFO - Running pre-action listener: _resource_record_pre_action 23:42:39 INFO - Running main action method: create_virtualenv 23:42:39 INFO - Creating virtualenv /builds/slave/test/build/venv 23:42:39 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:42:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:42:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:42:39 INFO - Using real prefix '/usr' 23:42:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:42:40 INFO - Installing distribute.............................................................................................................................................................................................done. 23:42:42 INFO - Installing pip.................done. 23:42:42 INFO - Return code: 0 23:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:42:42 INFO - Reading from file tmpfile_stdout 23:42:42 INFO - Output received: 23:42:42 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:42:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:42 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:42 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:42:42 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:42:42 INFO - Using env: {'DISPLAY': ':0', 23:42:42 INFO - 'HOME': '/home/cltbld', 23:42:42 INFO - 'LANG': 'en_US.UTF-8', 23:42:42 INFO - 'LANGUAGE': 'en_US:en', 23:42:42 INFO - 'LOGNAME': 'cltbld', 23:42:42 INFO - 'MAIL': '/var/mail/cltbld', 23:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:42 INFO - 'MOZ_NO_REMOTE': '1', 23:42:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:42 INFO - 'NO_EM_RESTART': '1', 23:42:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:42 INFO - 'PWD': '/builds/slave/test', 23:42:42 INFO - 'SHELL': '/bin/bash', 23:42:42 INFO - 'SHLVL': '1', 23:42:42 INFO - 'TERM': 'linux', 23:42:42 INFO - 'TMOUT': '86400', 23:42:42 INFO - 'USER': 'cltbld', 23:42:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:42 INFO - '_': '/tools/buildbot/bin/python'} 23:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:42 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:42:42 INFO - Cleaning up... 23:42:42 INFO - Return code: 0 23:42:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:42 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:42 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:42:42 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:42:42 INFO - Using env: {'DISPLAY': ':0', 23:42:42 INFO - 'HOME': '/home/cltbld', 23:42:42 INFO - 'LANG': 'en_US.UTF-8', 23:42:42 INFO - 'LANGUAGE': 'en_US:en', 23:42:42 INFO - 'LOGNAME': 'cltbld', 23:42:42 INFO - 'MAIL': '/var/mail/cltbld', 23:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:42 INFO - 'MOZ_NO_REMOTE': '1', 23:42:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:42 INFO - 'NO_EM_RESTART': '1', 23:42:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:42 INFO - 'PWD': '/builds/slave/test', 23:42:42 INFO - 'SHELL': '/bin/bash', 23:42:42 INFO - 'SHLVL': '1', 23:42:42 INFO - 'TERM': 'linux', 23:42:42 INFO - 'TMOUT': '86400', 23:42:42 INFO - 'USER': 'cltbld', 23:42:42 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:42 INFO - '_': '/tools/buildbot/bin/python'} 23:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:43 INFO - Downloading/unpacking psutil>=3.1.1 23:42:43 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:42:43 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:42:43 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:42:43 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:42:43 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:42:43 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:42:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:42:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:45 INFO - Installing collected packages: psutil 23:42:45 INFO - Running setup.py install for psutil 23:42:45 INFO - building 'psutil._psutil_linux' extension 23:42:45 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:42:45 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:42:45 INFO - building 'psutil._psutil_posix' extension 23:42:45 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:42:45 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:42:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:42:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:42:45 INFO - Successfully installed psutil 23:42:45 INFO - Cleaning up... 23:42:45 INFO - Return code: 0 23:42:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:45 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:45 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:42:45 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:42:45 INFO - Using env: {'DISPLAY': ':0', 23:42:45 INFO - 'HOME': '/home/cltbld', 23:42:45 INFO - 'LANG': 'en_US.UTF-8', 23:42:45 INFO - 'LANGUAGE': 'en_US:en', 23:42:45 INFO - 'LOGNAME': 'cltbld', 23:42:45 INFO - 'MAIL': '/var/mail/cltbld', 23:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:45 INFO - 'MOZ_NO_REMOTE': '1', 23:42:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:45 INFO - 'NO_EM_RESTART': '1', 23:42:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:45 INFO - 'PWD': '/builds/slave/test', 23:42:45 INFO - 'SHELL': '/bin/bash', 23:42:45 INFO - 'SHLVL': '1', 23:42:45 INFO - 'TERM': 'linux', 23:42:45 INFO - 'TMOUT': '86400', 23:42:45 INFO - 'USER': 'cltbld', 23:42:45 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:45 INFO - '_': '/tools/buildbot/bin/python'} 23:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:42:45 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:42:45 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:42:45 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:42:45 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:42:45 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:42:45 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:42:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:42:47 INFO - Installing collected packages: mozsystemmonitor 23:42:47 INFO - Running setup.py install for mozsystemmonitor 23:42:48 INFO - Successfully installed mozsystemmonitor 23:42:48 INFO - Cleaning up... 23:42:48 INFO - Return code: 0 23:42:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:42:48 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:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:48 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:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:48 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:48 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:42:48 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:42:48 INFO - Using env: {'DISPLAY': ':0', 23:42:48 INFO - 'HOME': '/home/cltbld', 23:42:48 INFO - 'LANG': 'en_US.UTF-8', 23:42:48 INFO - 'LANGUAGE': 'en_US:en', 23:42:48 INFO - 'LOGNAME': 'cltbld', 23:42:48 INFO - 'MAIL': '/var/mail/cltbld', 23:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:48 INFO - 'MOZ_NO_REMOTE': '1', 23:42:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:48 INFO - 'NO_EM_RESTART': '1', 23:42:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:48 INFO - 'PWD': '/builds/slave/test', 23:42:48 INFO - 'SHELL': '/bin/bash', 23:42:48 INFO - 'SHLVL': '1', 23:42:48 INFO - 'TERM': 'linux', 23:42:48 INFO - 'TMOUT': '86400', 23:42:48 INFO - 'USER': 'cltbld', 23:42:48 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:48 INFO - '_': '/tools/buildbot/bin/python'} 23:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:48 INFO - Downloading/unpacking jsonschema==2.5.1 23:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:42:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:42:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:42:50 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:42:50 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:42:50 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:42:50 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:42:50 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:42:50 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:42:50 INFO - Downloading functools32-3.2.3-2.tar.gz 23:42:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:42:50 INFO - warning: no files found matching '*.txt' 23:42:50 INFO - no previously-included directories found matching 'build' 23:42:50 INFO - no previously-included directories found matching 'dist' 23:42:50 INFO - no previously-included directories found matching '.git*' 23:42:50 INFO - Installing collected packages: jsonschema, functools32 23:42:50 INFO - Running setup.py install for functools32 23:42:50 INFO - warning: no files found matching '*.txt' 23:42:50 INFO - no previously-included directories found matching 'build' 23:42:50 INFO - no previously-included directories found matching 'dist' 23:42:50 INFO - no previously-included directories found matching '.git*' 23:42:50 INFO - Successfully installed jsonschema functools32 23:42:50 INFO - Cleaning up... 23:42:50 INFO - Return code: 0 23:42:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:42:50 INFO - Using env: {'DISPLAY': ':0', 23:42:50 INFO - 'HOME': '/home/cltbld', 23:42:50 INFO - 'LANG': 'en_US.UTF-8', 23:42:50 INFO - 'LANGUAGE': 'en_US:en', 23:42:50 INFO - 'LOGNAME': 'cltbld', 23:42:50 INFO - 'MAIL': '/var/mail/cltbld', 23:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:50 INFO - 'MOZ_NO_REMOTE': '1', 23:42:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:50 INFO - 'NO_EM_RESTART': '1', 23:42:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:50 INFO - 'PWD': '/builds/slave/test', 23:42:50 INFO - 'SHELL': '/bin/bash', 23:42:50 INFO - 'SHLVL': '1', 23:42:50 INFO - 'TERM': 'linux', 23:42:50 INFO - 'TMOUT': '86400', 23:42:50 INFO - 'USER': 'cltbld', 23:42:50 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:50 INFO - '_': '/tools/buildbot/bin/python'} 23:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:42:51 INFO - Cleaning up... 23:42:51 INFO - Return code: 0 23:42:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:42:51 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:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 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:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:51 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:51 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:42:51 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:42:51 INFO - Using env: {'DISPLAY': ':0', 23:42:51 INFO - 'HOME': '/home/cltbld', 23:42:51 INFO - 'LANG': 'en_US.UTF-8', 23:42:51 INFO - 'LANGUAGE': 'en_US:en', 23:42:51 INFO - 'LOGNAME': 'cltbld', 23:42:51 INFO - 'MAIL': '/var/mail/cltbld', 23:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:51 INFO - 'MOZ_NO_REMOTE': '1', 23:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:51 INFO - 'NO_EM_RESTART': '1', 23:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:51 INFO - 'PWD': '/builds/slave/test', 23:42:51 INFO - 'SHELL': '/bin/bash', 23:42:51 INFO - 'SHLVL': '1', 23:42:51 INFO - 'TERM': 'linux', 23:42:51 INFO - 'TMOUT': '86400', 23:42:51 INFO - 'USER': 'cltbld', 23:42:51 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:51 INFO - '_': '/tools/buildbot/bin/python'} 23:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:51 INFO - Downloading/unpacking blobuploader==1.2.4 23:42:51 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:42:51 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:42:51 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:42:51 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:42:51 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:42:51 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:42:53 INFO - Downloading blobuploader-1.2.4.tar.gz 23:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:42:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:42:53 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:42:53 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:42:53 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:42:53 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:42:53 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:42:53 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:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:42:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:42:53 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:42:53 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:42:53 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:42:53 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:42:53 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:42:53 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:42:53 INFO - Downloading docopt-0.6.1.tar.gz 23:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:42:54 INFO - Installing collected packages: blobuploader, requests, docopt 23:42:54 INFO - Running setup.py install for blobuploader 23:42:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:42:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:42:54 INFO - Running setup.py install for requests 23:42:54 INFO - Running setup.py install for docopt 23:42:54 INFO - Successfully installed blobuploader requests docopt 23:42:54 INFO - Cleaning up... 23:42:54 INFO - Return code: 0 23:42:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:54 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:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:54 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:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:54 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:54 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:42:54 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:42:54 INFO - Using env: {'DISPLAY': ':0', 23:42:54 INFO - 'HOME': '/home/cltbld', 23:42:54 INFO - 'LANG': 'en_US.UTF-8', 23:42:54 INFO - 'LANGUAGE': 'en_US:en', 23:42:54 INFO - 'LOGNAME': 'cltbld', 23:42:54 INFO - 'MAIL': '/var/mail/cltbld', 23:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:54 INFO - 'MOZ_NO_REMOTE': '1', 23:42:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:54 INFO - 'NO_EM_RESTART': '1', 23:42:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:54 INFO - 'PWD': '/builds/slave/test', 23:42:54 INFO - 'SHELL': '/bin/bash', 23:42:54 INFO - 'SHLVL': '1', 23:42:54 INFO - 'TERM': 'linux', 23:42:54 INFO - 'TMOUT': '86400', 23:42:54 INFO - 'USER': 'cltbld', 23:42:54 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:54 INFO - '_': '/tools/buildbot/bin/python'} 23:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:54 INFO - Running setup.py (path:/tmp/pip-op0Dj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:54 INFO - Running setup.py (path:/tmp/pip-1_JMVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:55 INFO - Running setup.py (path:/tmp/pip-FwykrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:55 INFO - Running setup.py (path:/tmp/pip-z3dDhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:55 INFO - Running setup.py (path:/tmp/pip-TsOLyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:55 INFO - Running setup.py (path:/tmp/pip-Nv4zM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:55 INFO - Running setup.py (path:/tmp/pip-J1LbGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:55 INFO - Running setup.py (path:/tmp/pip-QicToW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:55 INFO - Running setup.py (path:/tmp/pip-G1UhMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:55 INFO - Running setup.py (path:/tmp/pip-NjNNZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:55 INFO - Running setup.py (path:/tmp/pip-Iqmp1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:55 INFO - Running setup.py (path:/tmp/pip-fiHb9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:55 INFO - Running setup.py (path:/tmp/pip-jKYW90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:55 INFO - Running setup.py (path:/tmp/pip-tUN6_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:55 INFO - Running setup.py (path:/tmp/pip-wEFTy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:42:55 INFO - Running setup.py (path:/tmp/pip-XzmHGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:42:56 INFO - Running setup.py (path:/tmp/pip-UnspfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:42:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:42:56 INFO - Running setup.py install for manifestparser 23:42:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Running setup.py install for mozcrash 23:42:56 INFO - Running setup.py install for mozdebug 23:42:56 INFO - Running setup.py install for mozdevice 23:42:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Running setup.py install for mozfile 23:42:56 INFO - Running setup.py install for mozhttpd 23:42:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Running setup.py install for mozinfo 23:42:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Running setup.py install for mozInstall 23:42:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:42:56 INFO - Running setup.py install for mozleak 23:42:56 INFO - Running setup.py install for mozlog 23:42:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for moznetwork 23:42:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozprocess 23:42:57 INFO - Running setup.py install for mozprofile 23:42:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozrunner 23:42:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Running setup.py install for mozscreenshot 23:42:57 INFO - Running setup.py install for moztest 23:42:57 INFO - Running setup.py install for mozversion 23:42:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:42:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:42:57 INFO - Cleaning up... 23:42:57 INFO - Return code: 0 23:42:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:42:57 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:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:42:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:57 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:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:42:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:42:57 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:42:57 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:42:57 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:42:57 INFO - Using env: {'DISPLAY': ':0', 23:42:57 INFO - 'HOME': '/home/cltbld', 23:42:57 INFO - 'LANG': 'en_US.UTF-8', 23:42:57 INFO - 'LANGUAGE': 'en_US:en', 23:42:57 INFO - 'LOGNAME': 'cltbld', 23:42:57 INFO - 'MAIL': '/var/mail/cltbld', 23:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:42:57 INFO - 'MOZ_NO_REMOTE': '1', 23:42:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:42:57 INFO - 'NO_EM_RESTART': '1', 23:42:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:42:57 INFO - 'PWD': '/builds/slave/test', 23:42:57 INFO - 'SHELL': '/bin/bash', 23:42:57 INFO - 'SHLVL': '1', 23:42:57 INFO - 'TERM': 'linux', 23:42:57 INFO - 'TMOUT': '86400', 23:42:57 INFO - 'USER': 'cltbld', 23:42:57 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:42:57 INFO - '_': '/tools/buildbot/bin/python'} 23:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:42:58 INFO - Running setup.py (path:/tmp/pip-khu29X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:42:58 INFO - Running setup.py (path:/tmp/pip-vdYFaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:42:58 INFO - Running setup.py (path:/tmp/pip-WMCfJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:42:58 INFO - Running setup.py (path:/tmp/pip-plbSEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:42:58 INFO - Running setup.py (path:/tmp/pip-c2_Qgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:42:58 INFO - Running setup.py (path:/tmp/pip-R7WCzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:42:58 INFO - Running setup.py (path:/tmp/pip-axxHm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:42:58 INFO - Running setup.py (path:/tmp/pip-qSrqbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:42:58 INFO - Running setup.py (path:/tmp/pip-M0mban-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:42:58 INFO - Running setup.py (path:/tmp/pip-F_N6it-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:42:58 INFO - Running setup.py (path:/tmp/pip-Itv9ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:42:58 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:42:58 INFO - Running setup.py (path:/tmp/pip-OiACVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:42:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:42:58 INFO - Running setup.py (path:/tmp/pip-kGITi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:42:59 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:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:42:59 INFO - Running setup.py (path:/tmp/pip-wBW_Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:42:59 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:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:59 INFO - Running setup.py (path:/tmp/pip-2lVX0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:42:59 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:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:42:59 INFO - Running setup.py (path:/tmp/pip-qI83Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:42:59 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:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:42:59 INFO - Running setup.py (path:/tmp/pip-fd1Vuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:42:59 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:42:59 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:42:59 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:42:59 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:42:59 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:42:59 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:42:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:42:59 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:42:59 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:42:59 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:42:59 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:42:59 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:42:59 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:43:01 INFO - Downloading blessings-1.6.tar.gz 23:43:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:43:01 INFO - Installing collected packages: blessings 23:43:01 INFO - Running setup.py install for blessings 23:43:01 INFO - Successfully installed blessings 23:43:01 INFO - Cleaning up... 23:43:01 INFO - Return code: 0 23:43:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:43:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:01 INFO - Reading from file tmpfile_stdout 23:43:01 INFO - Current package versions: 23:43:01 INFO - argparse == 1.2.1 23:43:01 INFO - blessings == 1.6 23:43:01 INFO - blobuploader == 1.2.4 23:43:01 INFO - docopt == 0.6.1 23:43:01 INFO - functools32 == 3.2.3-2 23:43:01 INFO - jsonschema == 2.5.1 23:43:01 INFO - manifestparser == 1.1 23:43:01 INFO - mozInstall == 1.12 23:43:01 INFO - mozcrash == 1.0 23:43:01 INFO - mozdebug == 0.1 23:43:01 INFO - mozdevice == 0.49 23:43:01 INFO - mozfile == 1.2 23:43:01 INFO - mozhttpd == 0.7 23:43:01 INFO - mozinfo == 0.9 23:43:01 INFO - mozleak == 0.1 23:43:01 INFO - mozlog == 3.4 23:43:01 INFO - moznetwork == 0.27 23:43:01 INFO - mozprocess == 0.24 23:43:01 INFO - mozprofile == 0.28 23:43:01 INFO - mozrunner == 6.13 23:43:01 INFO - mozscreenshot == 0.1 23:43:01 INFO - mozsystemmonitor == 0.3 23:43:01 INFO - moztest == 0.8 23:43:01 INFO - mozversion == 1.4 23:43:01 INFO - psutil == 3.1.1 23:43:01 INFO - requests == 1.2.3 23:43:01 INFO - wsgiref == 0.1.2 23:43:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:01 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 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:43:01 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:43:01 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:43:01 INFO - Using env: {'DISPLAY': ':0', 23:43:01 INFO - 'HOME': '/home/cltbld', 23:43:01 INFO - 'LANG': 'en_US.UTF-8', 23:43:01 INFO - 'LANGUAGE': 'en_US:en', 23:43:01 INFO - 'LOGNAME': 'cltbld', 23:43:01 INFO - 'MAIL': '/var/mail/cltbld', 23:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:01 INFO - 'MOZ_NO_REMOTE': '1', 23:43:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:01 INFO - 'NO_EM_RESTART': '1', 23:43:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:01 INFO - 'PWD': '/builds/slave/test', 23:43:01 INFO - 'SHELL': '/bin/bash', 23:43:01 INFO - 'SHLVL': '1', 23:43:01 INFO - 'TERM': 'linux', 23:43:01 INFO - 'TMOUT': '86400', 23:43:01 INFO - 'USER': 'cltbld', 23:43:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:01 INFO - '_': '/tools/buildbot/bin/python'} 23:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 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:43:02 INFO - Cleaning up... 23:43:02 INFO - Return code: 0 23:43:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:43: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:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43: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:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:43:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xcff1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1071630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, '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': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', '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:43:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:43:02 INFO - Using env: {'DISPLAY': ':0', 23:43:02 INFO - 'HOME': '/home/cltbld', 23:43:02 INFO - 'LANG': 'en_US.UTF-8', 23:43:02 INFO - 'LANGUAGE': 'en_US:en', 23:43:02 INFO - 'LOGNAME': 'cltbld', 23:43:02 INFO - 'MAIL': '/var/mail/cltbld', 23:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:02 INFO - 'MOZ_NO_REMOTE': '1', 23:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:02 INFO - 'NO_EM_RESTART': '1', 23:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:02 INFO - 'PWD': '/builds/slave/test', 23:43:02 INFO - 'SHELL': '/bin/bash', 23:43:02 INFO - 'SHLVL': '1', 23:43:02 INFO - 'TERM': 'linux', 23:43:02 INFO - 'TMOUT': '86400', 23:43:02 INFO - 'USER': 'cltbld', 23:43:02 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:02 INFO - '_': '/tools/buildbot/bin/python'} 23:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:43:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:43:02 INFO - Cleaning up... 23:43:02 INFO - Return code: 0 23:43:02 INFO - Running post-action listener: _resource_record_post_action 23:43:02 INFO - Running post-action listener: _start_resource_monitoring 23:43:02 INFO - Starting resource monitoring. 23:43:02 INFO - [mozharness: 2017-01-17 07:43:02.442840Z] Finished create-virtualenv step (success) 23:43:02 INFO - [mozharness: 2017-01-17 07:43:02.443354Z] Running install step. 23:43:02 INFO - Running pre-action listener: _resource_record_pre_action 23:43:02 INFO - Running main action method: install 23:43:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:43:02 INFO - Reading from file tmpfile_stdout 23:43:02 INFO - Detecting whether we're running mozinstall >=1.0... 23:43:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:43:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:43:02 INFO - Reading from file tmpfile_stdout 23:43:02 INFO - Output received: 23:43:02 INFO - Usage: mozinstall [options] installer 23:43:02 INFO - Options: 23:43:02 INFO - -h, --help show this help message and exit 23:43:02 INFO - -d DEST, --destination=DEST 23:43:02 INFO - Directory to install application into. [default: 23:43:02 INFO - "/builds/slave/test"] 23:43:02 INFO - --app=APP Application being installed. [default: firefox] 23:43:02 INFO - mkdir: /builds/slave/test/build/application 23:43:02 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:43:02 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:43:20 INFO - Reading from file tmpfile_stdout 23:43:20 INFO - Output received: 23:43:20 INFO - /builds/slave/test/build/application/firefox/firefox 23:43:20 INFO - Running post-action listener: _resource_record_post_action 23:43:20 INFO - [mozharness: 2017-01-17 07:43:20.202906Z] Finished install step (success) 23:43:20 INFO - [mozharness: 2017-01-17 07:43:20.203186Z] Running run-tests step. 23:43:20 INFO - Running pre-action listener: _resource_record_pre_action 23:43:20 INFO - Running main action method: run_tests 23:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:43:20 INFO - Python 2.7.3 23:43:20 INFO - Return code: 0 23:43:20 INFO - grabbing minidump binary from tooltool 23:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:43:20 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 0x10fa850>, '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 0x106f6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:43:20 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:43:20 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:43:20 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:43:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:43:20 INFO - Return code: 0 23:43:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:43:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:43:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:43:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:43:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:43:20 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/1484631013/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:43:20 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/1484631013/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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:43:20 INFO - Using env: {'DISPLAY': ':0', 23:43:20 INFO - 'HOME': '/home/cltbld', 23:43:20 INFO - 'LANG': 'en_US.UTF-8', 23:43:20 INFO - 'LANGUAGE': 'en_US:en', 23:43:20 INFO - 'LOGNAME': 'cltbld', 23:43:20 INFO - 'MAIL': '/var/mail/cltbld', 23:43:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:43:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:43:20 INFO - 'MOZ_NO_REMOTE': '1', 23:43:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:43:20 INFO - 'NO_EM_RESTART': '1', 23:43:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:43:20 INFO - 'PWD': '/builds/slave/test', 23:43:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:43:20 INFO - 'SHELL': '/bin/bash', 23:43:20 INFO - 'SHLVL': '1', 23:43:20 INFO - 'TERM': 'linux', 23:43:20 INFO - 'TMOUT': '86400', 23:43:20 INFO - 'USER': 'cltbld', 23:43:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384', 23:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:43:20 INFO - '_': '/tools/buildbot/bin/python'} 23:43:20 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/1484631013/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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:43:20 INFO - mozversion application_buildid: 20170116213013 23:43:20 INFO - mozversion application_changeset: 14a12ac05a4b9dddeb6919540ab05af08e730912 23:43:20 INFO - mozversion application_display_name: Nightly 23:43:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:43:20 INFO - mozversion application_name: Firefox 23:43:20 INFO - mozversion application_remotingname: firefox 23:43:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:43:20 INFO - mozversion application_vendor: Mozilla 23:43:20 INFO - mozversion application_version: 53.0a1 23:43:20 INFO - mozversion platform_buildid: 20170116213013 23:43:20 INFO - mozversion platform_changeset: 14a12ac05a4b9dddeb6919540ab05af08e730912 23:43:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:43:20 INFO - mozversion platform_version: 53.0a1 23:43:20 INFO - using testdate: 1484639000 23:43:20 INFO - actual date: 1484639000 23:43:20 INFO - starting webserver on 'localhost:44920' 23:43:20 INFO - SUITE-START | Running 2 tests 23:43:20 INFO - TEST-START | dromaeo_css 23:43:20 INFO - operating with platform_type : linux_ 23:43:20 INFO - Initialising browser for dromaeo_css test... 23:43:20 INFO - TEST-INFO | started process 29364 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZPNFc9/profile http://localhost:44920/getInfo.html) 23:43:24 INFO - PROCESS | 29364 | __metrics Screen width/height:1600/1200 23:43:24 INFO - PROCESS | 29364 | colorDepth:24 23:43:24 INFO - PROCESS | 29364 | Browser inner width/height: 1024/697 23:43:24 INFO - PROCESS | 29364 | __metrics 23:43:24 INFO - PROCESS | 29364 | 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:43:24 INFO - PROCESS | 29364 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:43:25 INFO - PROCESS | 29364 | console.error: 23:43:25 INFO - PROCESS | 29364 | Message: Error: Connection closed before committing the transaction. 23:43:25 INFO - PROCESS | 29364 | Stack: 23:43:25 INFO - PROCESS | 29364 | executeTransaction/promise resource://gre/modules/Promise-backend.js:917:23 23:43:25 INFO - PROCESS | 29364 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:801:7 23:43:25 INFO - PROCESS | 29364 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:734:11 23:43:25 INFO - PROCESS | 29364 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:765:7 23:43:25 INFO - PROCESS | 29364 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:702:7 23:43:25 INFO - PROCESS | 29364 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 23:43:25 INFO - PROCESS | 29364 | observe@resource://gre/modules/AsyncShutdown.jsm:550:9 23:43:25 INFO - PROCESS | 29364 | addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 23:43:25 INFO - PROCESS | 29364 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:385:5 23:43:25 INFO - PROCESS | 29364 | moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 23:43:25 INFO - PROCESS | 29364 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 23:43:25 INFO - PROCESS | 29364 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 23:43:25 INFO - PROCESS | 29364 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 23:43:25 INFO - PROCESS | 29364 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 23:43:25 INFO - PROCESS | 29364 | 23:43:25 INFO - PROCESS | 29364 | console.error: PushService: 23:43:25 INFO - PROCESS | 29364 | stateChangeProcessEnqueue: Error transitioning state 23:43:25 INFO - PROCESS | 29364 | UnknownError 23:43:26 INFO - TEST-INFO | 29364: exit 0 23:43:26 INFO - Browser initialized. 23:43:26 INFO - Running cycle 1/1 for dromaeo_css test... 23:43:26 INFO - TEST-INFO | started process 29445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZPNFc9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:43:38 INFO - PROCESS | 29445 | RSS: Main: 148369408 23:43:38 INFO - PROCESS | 29445 | 23:46:38 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44920/tests/dromaeo/cssquery-ext.html) 23:46:40 INFO - PROCESS | 29445 | RSS: Main: 278315008 23:46:40 INFO - PROCESS | 29445 | 23:48:27 INFO - PROCESS | 29445 | ************************* 23:48:27 INFO - PROCESS | 29445 | A coding exception was thrown and uncaught in a Task. 23:48:27 INFO - PROCESS | 29445 | 23:48:27 ERROR - PROCESS | 29445 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:48:27 INFO - PROCESS | 29445 | Full stack: 23:48:27 INFO - PROCESS | 29445 | ************************* 23:49:42 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-ext.html (next: http://localhost:44920/tests/dromaeo/cssquery-jquery.html) 23:49:42 INFO - PROCESS | 29445 | RSS: Main: 239349760 23:49:42 INFO - PROCESS | 29445 | 23:52:44 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44920/tests/dromaeo/cssquery-mootools.html) 23:52:45 INFO - PROCESS | 29445 | RSS: Main: 180277248 23:52:45 INFO - PROCESS | 29445 | 23:55:47 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44920/tests/dromaeo/cssquery-prototype.html) 23:55:48 INFO - PROCESS | 29445 | RSS: Main: 223494144 23:55:48 INFO - PROCESS | 29445 | 23:58:50 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44920/tests/dromaeo/cssquery-yui.html) 23:58:51 INFO - PROCESS | 29445 | RSS: Main: 179191808 23:58:51 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | Cycle 1(1): loaded http://localhost:44920/tests/dromaeo/cssquery-yui.html (next: http://localhost:44920/tests/dromaeo/cssquery-dojo.html) 00:01:54 INFO - PROCESS | 29445 | RSS: Main: 276135936 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | __start_tp_report 00:01:54 INFO - PROCESS | 29445 | _x_x_mozilla_page_load 00:01:54 INFO - PROCESS | 29445 | _x_x_mozilla_page_load_details 00:01:54 INFO - PROCESS | 29445 | |i|pagename|runs| 00:01:54 INFO - PROCESS | 29445 | |0;dojo.html;3194.680531946806;3205.022468147214;3211.341674956634;3221.6295126060495;3263.918402039949;5239.240310155034;5273.367195936484;5344.973275133624;5440.5375543078835;5457.099578569536;5357.303550538434;5365.222042803791;5368.570514358852;5378.946210537893;5379.300690910186;4752.572268495844;4774.2122549779415;4800.87997800056;4846.4911184325665;4895.8776030599165;4877;4889.071076495472;4890.77991490382;4920.901581968376;5000.799968001275;4967.10592093423;4977.129002424579;4977.203647416414;4978.054169707744;5016.724080175589;405.4539847051125;4814.398200224972;4828.855134345975;4909.26361045846;4970.776315065813;4864.0028794097125;4872.634552408548;4873.269009648581;4876.634252431082;4909.337239472661;5381.0583147948955;5382.273393091961;5386.542143917772;5426.968875913563;5432.429594892558;5566.749496272678;5582.553395728332;5691.9146212806845;5794.101914203306;5866.002779527491;5779.422057794229;5783.334916484574;5825.941740582624;5885.61743486672;5887.911681324784;5419.970205660912;5421.322334708161;5425.945740542623;5427.83716488506;5430.864228394283;5703.287089113861;5734.942650573525;5947.078202878561;5952.583319167703;5962.165296858443;5566.610337276433;5586.832395028156;5599.440055994368;5640.210370548127;5680.715964201773;5703.486686198262;5704.71476426177;5705.885882282414;5706.657600543897;5711.314642242957;5530.695811730394;5633.070543360382;5672.546196304285;5686.289213848269;5693.857653558694;5566.610337276433;5661.065924122474;5670.716464176775;5704.058830293038;5790.594658373958;4486.484054333793;4489.7979590918485;4495.595396414273;4496.707713998645;4532.388127602798;3928.6071392860486;3957.445957565943;4002.7198096133575;4012.378081397388;4046.069404037088;5401.675899445967;5454.318210223722;5490.478404551562;5520.089185284383;5536.474034966672;5834.883302334015;5877.23595932526;5880.176775251468;5892.057270836645;5916.556258280646;5488.451154884479;5496.972515137399;5499.092649712753;5502.0921547944945;5509.421510741395;4787.210110331756;4832.710037397767;4971.105201063771;5011.1481048221885;5063.8480845574695;6010.459058684739;6118.101457354478;6118.293648225375;6118.4059889896;6123.069106197231;5867.237259156283;5881.911771323348;5884.35272120075;5889.5582831286965;5900.527957763283;5854.590178687621;6011.098335249748;6064.514838812973;6077.058056001327;6084.695765211633;5581.609287349846;5610.6072574921;5639.492445679908;5670.886582268414;5775.1914731938405;5759.94240057594;5798.01433756254;5799.159121927212;5814.447627475383;5814.912776308443;5643.689597072201;5671.801486948102;5675.716214189191;5677.176809362537;5679.488846003879;5508.476694713996;5508.504234618904;5638;5661.122526008476;5723.427657234243;5739.426057394227;5741.827745167651;5754.769809207753;5757.8560535986935;5776.306843178845;5772.624779389326;5802.970985145047;5803.7968671096305;5804.477597016289;5806.390329015561;5364.436734143015;5397.7840886365675;5594.4125866785025;5655.038643430545;5667.3482549507335 00:01:54 INFO - PROCESS | 29445 | |1;ext.html;554.0006901046658;583.7373182068069;605.391581460632;632.4213344789517;653.3009679642784;33709;45000.54999450005;46717.29924051142;46803.76598117013;46939.530604693944;47358;47701.7614911925;47744.52255477444;47796;47800.52199478004;8618.439801412887;8935.463872167658;9168.679096231634;9174.266058715286;9178.403403778766;8615.526146061964;10719.517621707042;10945;10948.45257737114;11296.378699171584;10966.835497467544;11031.944840275828;11042.33745975244;11047.281926674781;11050.226484146113;10895.128389728803;10936.234463587552;10955.83566246507;10956.397398143099;10965.451727413638;10780.191485638608;10851.565937362497;10871.83692244617;10872.619458319;10876.18428617849;44450.77774611139;44747;44795.77602111969;44827;44905.77547112276;4249.6387807036435;4295.806688699016;4342.6308763754805;4439.733615983051;4484.1928452878465;32772.6722732772;33215;33242.3351532968;33604;35025.29949401023;4311.719738217006;4403.097365040191;4474.373587697725;4476.709013914117;4499.415076040126;327905.36047319847;332454;332459;333022;333184;63857.36142638607;63977;64017;64293;64379.67810160919;66685;66918.66540667362;67071.32928670748;67107.66446167738;67233.32766672368;17038.06290654026;17534.386296479563;17541.210645520983;17542;17543.912280438515;12583.11918165738;13112.147710398793;13479.326033698277;13672.31638418075;13760.243186624632;18373;18950.526236843914;19174.424767257005;19295.710564341545;19336.4199074028;6599.637019963948;6657.567258128206;6734.730610775514;6837.726490940406;6903.896441553381;941.3081385181886;946.0208684012015;959.52023988006;966.4104896013426;998.1366118307611;31419.685803142132;32398;32413.18967025796;32546.511802322984;32704.945270226875;1057.1595581512715;1096.7696783675517;1104.3886491173816;1123.25864929148;1138.5502726423183;16142.757858631894;17261.223244954006;17324.740128898076;17420.47738567845;17474.65050698979;449667;449982;451228;453391;457546;62838.37161628417;63080.36919630745;63097.68451157807;63181.36818631847;63270.68364658147;1463.5463006468028;1468.7062587482546;1476.8670819626286;1479.963000924964;1510.5241848817807;29646.555301670924;31743.841280793447;32045.519317209793;32114.678853211168;32697.34605307833;51728;53257.733711331195;53330;53378.73310633422;53407.7329613365;355109;358546;359021;359538;360536;41520.58479415288;41699.37450938299;41826.516889882114;42013.15973680448;42319.7884010578;45597.31604025876;46676.53323466722;47158.29262560996;47282;47454;38844.80577597094;39174.216515670094;39256;39821.2035759289;41372.37941430941;38057.23885522219;38493.4225986616;38829.22341553096;40036;40103.3984490227;39712;40351;40852.79573602113;40951.7952410236;41175.17649646931;32600.51099233466;34436.311273773885;35110.29779404448;35178.82410587931;35350.82324588361;26384.340391489597;27041.86479067592;27094.94981679127;27113.86443067772;27323 00:01:54 INFO - PROCESS | 29445 | |2;jquery.html;396.69717291125045;410.4049128763292;427.93153095504715;428.1608048225478;441.1683975705794;15773.526794196163;19926.800731992695;21296.46758831028;21400.89299553504;21527.784722152774;21163.682544761803;21803.890980545115;21947.67078493824;22153.44616384591;22213.888930555364;5818.854528636797;5836.387179346171;5915.319738230116;6080.2399700037495;6086.847828804271;5856.0630299151935;5956.583039187259;5961.135635332874;5962.0162673158875;5967.254093238345;5687;5820.941790582104;5821.359650438449;5922.845045216184;5929.555283353766;5851.4148585141575;5887.705614719247;5935.10973353996;5937.643741375509;5940.821775346725;5198.454162312941;5233.4243233244315;5267.26258323835;5268.341457317835;5276.287701160332;20338;20445.488862778402;23988.52022959531;24351;24503.632445513333;3003.684613115613;3061.0969763919697;3073.800202986821;3093.721565059133;3161.668024857403;15042.32309546073;16541.66916661672;16700.74948875768;16901.154942252837;17294.65410691779;2172.6089303925287;2207.9889600551896;2228.5097278598682;2242.9887850560644;2293.598620241468;103054.48472757588;105345.47327263391;105406.47296763543;106402.46798765956;108231;15130.773038404433;18988.62022759537;19507.51231219231;19522.511937201685;19844.503887402643;34036.829815851255;36900.81549592235;37042.62957370446;37172;37347.25305493875;10503.479165000344;10586.629467968583;10590.788184236271;10841.783164336826;10943.179261555415;8620.396572240008;8717.128287171232;8737.126287371211;8920.8661870072;8956;10044.598216071421;10170.745731356776;10227.59089636421;10266.6406675766;10342.37945723259;3749.9062523437133;3897.395903634941;3899.454076429302;4093.488313960755;4170.374443833449;881.2465342132513;885.6545947080643;888.9777755556163;895.8924929008431;902.9210392561334;19039;19780.20879164846;20408.28571000008;21009.579808403745;21128.577428451652;893.6595107338992;923.9907600923905;936.8735220745249;937.6062053937362;940.9623615055458;11025.83461248082;11374.374409407397;11559.537140724151;11648.068154547616;11666.416679166008;540646;550322;564363;567740;579133;99017.50491247498;100500;102679;103394.48302758439;104892;1045.1743122932799;1049.480507148951;1114.5207560748793;1118.3066498770813;1118.4351902289454;18539.907300463412;18554.907225463787;18817.811821881605;18819;18874.52813679614;40417.39373909453;42352;45386.77306613578;45635.54364456446;45717.771411142734;62107.6894615524;62222.377776221656;63296.56924015275;64238.67880660567;64566.677166615744;8762.123787621187;8833.249173820297;8849.469031857852;8936.508492033001;9108.180263776027;13622.86377136216;13677.452901883813;13888.305584720925;13930.930345348208;13951.720965580429;11330.206885517935;11553.537858485566;11559.248648837798;11586.304821710724;12315.322657253937;10673.839892401775;11037.503312351124;11175.944120279346;11251.887481125084;11473.196876218584;11736.237144585575;12520.812187817372;12530.561430349893;12750.489980401051;12805.55180568713;8735.65057397697;8941.37410381267;9011.098890110936;9131.634734610807;9385.343025988115;9095.090490950852;11960.58137965167;12017.75964480688;12050.698732531406;12306.630801075982 00:01:54 INFO - PROCESS | 29445 | |3;mootools.html;1450.08644553931;1453.0990785712859;1461.1963420118932;1477.3573495529452;1478.770790527468;8234.094249632542;8707.782305442359;8740.737777866674;8742.388032837704;8752.212300892917;7907.960460197691;8156.347492200624;8222.34221262299;8356.456830306026;8609.526476043815;2392.521495700858;2428.623563347675;2430.513897220554;2432.1487479382254;2449.5590793657134;2359.0563774490256;2374.7862692357685;2391.175044609607;2397.5444665513583;2440.2923152285816;2377.405648587853;2385.689860318156;2404.603240465321;2407.181558270188;2407.9759202408013;2297;2342.3090188394463;2343.3907184132077;2373.655819906121;2378.3935096550354;2314.895829687668;2332.2187067332466;2381.9523609527887;2385.1294277588554;2389.390705370137;8585.484870907703;8670.609822558;8680.218780309802;8680.435771674822;8689.131086891324;5311.893762124736;5470.234167216553;5471.699056551888;5473.27591808579;5475.479829416199;3468.843902024415;3528.5942116656656;3544.3442963051916;3567.625399333059;3624.655657712513;1346.838379394469;1349.0893646788477;1358.2733237717773;1389.0970868935171;1420.9857901420958;12040.578579749754;12051.15641905067;12058.6382408527;12076.21504602198;12089.455974481169;4616.630669546428;4894.19245824435;4912.6561140719805;4929.7535123244415;4936.6297527685565;10364.067233948981;10378.636747713794;10378.844317335246;10387.584496620202;10391.896081039093;5579.246801681802;5586.329640443091;5592.273004509388;5595.440455954372;5597.216389705444;4991.950080499221;5044.1677123274985;5121.334226550524;5125.333706618116;5125.487451254845;4008.158286759755;4021.0286329945466;4051.918961620751;4071.898202544901;4099.467069280974;1340.2628554295293;1347.7371912476972;1348.7774517204748;1354.939027743754;1364.379207460603;945.4705364995624;971.3055165556519;972.6644307713827;978.2711879649706;979.3487330924997;5573.860653483614;5584.636998595079;5637.9718101409235;5808.651480911159;5810.157527158538;1019.1439191079529;1038.3665963762098;1058.4178701714181;1063.5958335832488;1077.2405454154803;3858.3055050090693;3956.663683586927;4020.819063142107;4026.516817981861;4099.8975025624595;10262.692119236435;10448.477576121164;10541.31481453703;10664.733381665521;10666.893331066745;10394.22043346752;10457.42484163363;10493.055624993787;10523.158147348193;10530.736731581772;8159.347252219808;8344.290735287446;8349.958250208832;8365.288950439159;8371.288440482505;5645.40723224072;5661.603687741818;5664.85837854057;5717.914231286451;5798.710064496674;9796.706098817047;9815.920248772773;9825.459599722084;9866.506674666382;9921.503924803586;8991.775205619912;9296.116868897443;9776.462294573867;9826.361286516354;10094.646687365906;6945.756898508528;7093.432525398059;7095.7293666028445;7119.750808721877;7221.025161603116;8132.593370331341;8317.542535160625;8358.749237522885;8366.66533338684;8586;7454.366378857858;7474.962625186839;7492.100947886289;7677.424193185645;7688.961555192188;7249.528780629243;7260.782176534713;7418.554886706815;7428.325802711128;7478.551286922802;6458.903116453351;6574.506911981716;6624.668766561749;6755.763548275983;6765.9661701691175;4973.651844370859;5010.398752149766;5117.360329958755;5152.613553983391;5175.9741201293755;4976.2286845539;5116.590672746097;5121.590272778094;5205.6356055075785;5220.347456567929 00:01:54 INFO - PROCESS | 29445 | |4;prototype.html;396.1008510680755;410.8705757686329;414.0745433955108;415.6716194206577;418.8680565621827;4991.775370108345;5104.3619547556555;5240.44975277596;5252.395974462938;5272.973635131819;5106.540411362977;5134.768935397907;5236.423993360727;5241.737913104349;5250.579953603712;4165.458490396252;4187.350960601112;4193.9580604194025;4197;4198.706090573661;4013.6588389986887;4068.490415335469;4185.539590645027;4207.15856828634;4210.3894935234375;3983.6016398360075;4161.169085602341;4175.916481670365;4195.916081678364;4200.873973780776;4165.062860856298;4170.374443833419;4175.331946888514;4184.3305071188615;4188.32986722123;3811.161544460242;3814.1799513104816;3815.7138214633737;3826.4069069294305;3828.885133446001;4986.426560945465;5129.974350128263;5145.897082058338;5161.896762064775;5167.974160129213;4901.338319326881;4903.681260718042;4911.263310503417;4911.705297682151;4930.112579735646;5436.184572314145;5575.414581468928;5580.651609034588;5581.358143813432;5586.329640443132;4179.623833854937;4212.957870421318;4215.494140703105;4218.810153543098;4230.936535951963;5692.6299790513485;5706;5713.171590119409;5724.4561766632105;5730.856728581777;4360.563943605614;4369.531214863592;4370.2352088384405;4375.387445757597;4391.67062470316;4184.422318917343;4417.469903611587;4417.646588272931;4456.3288766712485;4456.844010459683;4891.1685013547785;4892.412910450768;4892.902141957212;4894.241392584155;4894.853154405374;5452.427495112956;5600.459873534811;5612.466815652507;5622.57830662702;5625.774969001276;1438.6547228665045;1452.375478544236;1462.6343414146463;1487.315834716021;1494.3798323695544;1052.2529004406956;1081.4700796609761;1087.260662749338;1090.8091084060259;1094.5457635081511;5173.870653233624;5185.377754669388;5216.608754343363;5253.55344795689;5283.101872681654;5314.468553144655;5477.281168750774;5514.448555144453;5541.838682913667;5564.805231816867;5156.793728250828;5165.74171291434;5176.757578181209;5180.119379705459;5187.610929180327;2880.438314528689;2891.8843246270335;2918.599072445196;2920.956185657217;2926.341573146025;5558.666480011212;5671.8865622688145;5703.857403564944;5878.941210587925;5919.968027176951;5783.351744752726;5796.47831695141;5803.796867109715;5815.82552523425;5820.70896455184;5733.627314224562;5853.209816674779;5881.7647294107755;5895.793647222326;5908.231929849178;5584.413636568264;5630.211770352236;5650.208970744181;5676.672564248638;5682.659040457585;5623.634463759842;5638.323401191883;5649.067903795828;5661.537231304395;5673.092305231144;5684.8010319638615;5841.474267315792;5869.765209391577;5903.291605007426;5904.0553511437965;4117.876463706094;4125.546189919167;4126.958730412657;4159.916801664011;4161.4382058421725;5675.531143952394;5679.460451257124;5684.232628595087;5689.402612725604;5728.799492017926;5301;5527.917081243704;5546.36216835053;5547.944520554905;5571.9721401392735;5497.890042199214;5539.861503462445;5556.638818476786;5562.5549956002615;5568.2761241040025;5265.7367131642495;5359.276497672764;5443.047466693233;5533.5849811265125;5542.556595472433;5528.75120619565;5557.277553917965;5604.943950560442;5605.711630602674;5613.494785469164;5231.55531779803;5433.359649035235;5438.211459338453;5443.972780136232;5459.836204913859 00:01:54 INFO - PROCESS | 29445 | |5;yui.html;747.2826086956518;751.4739682222446;788.6135793461206;859.8597751824964;898.564196364763;14725.926370368146;16368.345266189353;16684.41604543841;16719.58201044973;16739.83260167398;15365;16027.198640068009;16028.35886564536;16031.198440078007;16049.679006419894;580.2659635561018;594.6937327276435;734.3537686835577;745.79490640074;763.427429427929;749.0973377080632;763.1224092293878;766.0768773627793;767.8502691975067;769.86142494351;803.7106641609016;803.7869964459423;804.0793291681022;804.2721337189823;804.4087595617217;672.9327067293256;690.9309069093101;764.182324912344;800.4996876951905;800.6957356204634;578.7627072900133;582.6416753696489;582.7872826418369;583.1689841975168;583.3990989281004;16179.191040447931;16250.512484625362;16318.592035199097;16337.754933676004;16369.345226190939;15039;15486.61283467911;15844;15983.120928348937;16063.036217826968;13567;13674.31628418585;13826.861731382658;13924.93037534816;13931.30343482832;4589.577275777237;4619.307103934403;4622.399088118556;4650.162970665313;4670.299455081731;80712;82177.17822821756;83298.99999999939;83413.16586834175;83511;12031.398430078636;12079.57721479744;12101.394930253453;12194.695132621755;12320.691982700504;23406.414839629146;23595.29214123579;23792.643110353358;23807.761922380556;23943.880280598485;8179.248412120772;8375.811397395544;8491.830163396697;8501.787455313666;8775.078616745277;4335.414719012956;4404.141192467502;4434.467863856294;4479.037007043501;4488;3485.1112966193964;3540.5751309842985;3566.7859928404378;3570.125319296737;3579.4451859961746;648.4423395879539;664.6643445060214;667.8263651450658;669.4343279928453;677.0487465111527;781.9218078192135;785.7878372839301;811.1401913971209;811.4401063266328;846.0481957797477;10269.7432564185;10960.177986651033;11244.325340479598;11532;11575.363355015555;764.1632412508294;791.1416113516783;801.254833005299;808.1191501263651;817.7832874288318;5195.350581177353;5374.1938709193155;5398.433164517748;5404.567634589223;5425.810096646598;78257.60871195607;79389;82059;82322;84136;56482.7175864118;59284.70357648184;71305.2869471309;73342.63328683429;73821.26178738145;57183.71408142932;57420.13869791957;57438;58045.70977145087;59316.40683593109;10716.73208169802;11176.497057632589;11482.94258528702;11569.305841649528;11570.652880413601;38554.22891542209;38861;39460.40809387919;40225.396619051324;40329.39505907355;45391.31913021241;48115.75942120267;48527.75736121297;49580.25629615631;51090.74454627852;13436.126651767603;13484.86515134836;13609.931950340186;13725.107867988549;13791.931040344734;15247.466338678092;15442.382304708135;15460.304286307368;15489.225538722794;15520.940396954415;13821.861781382058;14011.789823152865;14543;14650.853491465376;15142.924285378502;13538.458461661423;13861.722765544835;13975.650608734863;14551.636209094433;14671;13749.106308089942;13869.86130138726;13966.790498142333;14194.3612537434;14207.360668770138;11207.693730349403;11546.826797597874;11767.11746619024;12080.577179798665;12096.274223546616;11594.362310073027;11616.361100139573;11770;11772.411379431165;11820.763584728085 00:01:54 INFO - PROCESS | 29445 | __end_tp_report 00:01:54 INFO - PROCESS | 29445 | __start_cc_report 00:01:54 INFO - PROCESS | 29445 | _x_x_mozilla_cycle_collect,1119 00:01:54 INFO - PROCESS | 29445 | __end_cc_report 00:01:54 INFO - PROCESS | 29445 | __startTimestamp1484640114546__endTimestamp 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | ------- Summary: start ------- 00:01:54 INFO - PROCESS | 29445 | Number of tests: 6 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#0] dojo.html Cycles:165 Average:5347.96 Median:5566.75 stddev:715.35 (12.9%) stddev-sans-first:697.31 00:01:54 INFO - PROCESS | 29445 | Values: 3194.7 3205.0 3211.3 3221.6 3263.9 5239.2 5273.4 5345.0 5440.5 5457.1 5357.3 5365.2 5368.6 5378.9 5379.3 4752.6 4774.2 4800.9 4846.5 4895.9 4877.0 4889.1 4890.8 4920.9 5000.8 4967.1 4977.1 4977.2 4978.1 5016.7 405.5 4814.4 4828.9 4909.3 4970.8 4864.0 4872.6 4873.3 4876.6 4909.3 5381.1 5382.3 5386.5 5427.0 5432.4 5566.7 5582.6 5691.9 5794.1 5866.0 5779.4 5783.3 5825.9 5885.6 5887.9 5420.0 5421.3 5425.9 5427.8 5430.9 5703.3 5734.9 5947.1 5952.6 5962.2 5566.6 5586.8 5599.4 5640.2 5680.7 5703.5 5704.7 5705.9 5706.7 5711.3 5530.7 5633.1 5672.5 5686.3 5693.9 5566.6 5661.1 5670.7 5704.1 5790.6 4486.5 4489.8 4495.6 4496.7 4532.4 3928.6 3957.4 4002.7 4012.4 4046.1 5401.7 5454.3 5490.5 5520.1 5536.5 5834.9 5877.2 5880.2 5892.1 5916.6 5488.5 5497.0 5499.1 5502.1 5509.4 4787.2 4832.7 4971.1 5011.1 5063.8 6010.5 6118.1 6118.3 6118.4 6123.1 5867.2 5881.9 5884.4 5889.6 5900.5 5854.6 6011.1 6064.5 6077.1 6084.7 5581.6 5610.6 5639.5 5670.9 5775.2 5759.9 5798.0 5799.2 5814.4 5814.9 5643.7 5671.8 5675.7 5677.2 5679.5 5508.5 5508.5 5638.0 5661.1 5723.4 5739.4 5741.8 5754.8 5757.9 5776.3 5772.6 5803.0 5803.8 5804.5 5806.4 5364.4 5397.8 5594.4 5655.0 5667.3 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#1] ext.html Cycles:180 Average:56452.95 Median:39484.00 stddev:101078.32 (256.0%) stddev-sans-first:101274.73 00:01:54 INFO - PROCESS | 29445 | Values: 554.0 583.7 605.4 632.4 653.3 33709.0 45000.5 46717.3 46803.8 46939.5 47358.0 47701.8 47744.5 47796.0 47800.5 8618.4 8935.5 9168.7 9174.3 9178.4 8615.5 10719.5 10945.0 10948.5 11296.4 10966.8 11031.9 11042.3 11047.3 11050.2 10895.1 10936.2 10955.8 10956.4 10965.5 10780.2 10851.6 10871.8 10872.6 10876.2 44450.8 44747.0 44795.8 44827.0 44905.8 4249.6 4295.8 4342.6 4439.7 4484.2 32772.7 33215.0 33242.3 33604.0 35025.3 4311.7 4403.1 4474.4 4476.7 4499.4 327905.4 332454.0 332459.0 333022.0 333184.0 63857.4 63977.0 64017.0 64293.0 64379.7 66685.0 66918.7 67071.3 67107.7 67233.3 17038.1 17534.4 17541.2 17542.0 17543.9 12583.1 13112.1 13479.3 13672.3 13760.2 18373.0 18950.5 19174.4 19295.7 19336.4 6599.6 6657.6 6734.7 6837.7 6903.9 941.3 946.0 959.5 966.4 998.1 31419.7 32398.0 32413.2 32546.5 32704.9 1057.2 1096.8 1104.4 1123.3 1138.6 16142.8 17261.2 17324.7 17420.5 17474.7 449667.0 449982.0 451228.0 453391.0 457546.0 62838.4 63080.4 63097.7 63181.4 63270.7 1463.5 1468.7 1476.9 1480.0 1510.5 29646.6 31743.8 32045.5 32114.7 32697.3 51728.0 53257.7 53330.0 53378.7 53407.7 355109.0 358546.0 359021.0 359538.0 360536.0 41520.6 41699.4 41826.5 42013.2 42319.8 45597.3 46676.5 47158.3 47282.0 47454.0 38844.8 39174.2 39256.0 39821.2 41372.4 38057.2 38493.4 38829.2 40036.0 40103.4 39712.0 40351.0 40852.8 40951.8 41175.2 32600.5 34436.3 35110.3 35178.8 35350.8 26384.3 27041.9 27094.9 27113.9 27323.0 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#2] jquery.html Cycles:180 Average:33658.32 Median:24169.76 stddev:92555.26 (382.9%) stddev-sans-first:92781.21 00:01:54 INFO - PROCESS | 29445 | Values: 396.7 410.4 427.9 428.2 441.2 15773.5 19926.8 21296.5 21400.9 21527.8 21163.7 21803.9 21947.7 22153.4 22213.9 5818.9 5836.4 5915.3 6080.2 6086.8 5856.1 5956.6 5961.1 5962.0 5967.3 5687.0 5820.9 5821.4 5922.8 5929.6 5851.4 5887.7 5935.1 5937.6 5940.8 5198.5 5233.4 5267.3 5268.3 5276.3 20338.0 20445.5 23988.5 24351.0 24503.6 3003.7 3061.1 3073.8 3093.7 3161.7 15042.3 16541.7 16700.7 16901.2 17294.7 2172.6 2208.0 2228.5 2243.0 2293.6 103054.5 105345.5 105406.5 106402.5 108231.0 15130.8 18988.6 19507.5 19522.5 19844.5 34036.8 36900.8 37042.6 37172.0 37347.3 10503.5 10586.6 10590.8 10841.8 10943.2 8620.4 8717.1 8737.1 8920.9 8956.0 10044.6 10170.7 10227.6 10266.6 10342.4 3749.9 3897.4 3899.5 4093.5 4170.4 881.2 885.7 889.0 895.9 902.9 19039.0 19780.2 20408.3 21009.6 21128.6 893.7 924.0 936.9 937.6 941.0 11025.8 11374.4 11559.5 11648.1 11666.4 540646.0 550322.0 564363.0 567740.0 579133.0 99017.5 100500.0 102679.0 103394.5 104892.0 1045.2 1049.5 1114.5 1118.3 1118.4 18539.9 18554.9 18817.8 18819.0 18874.5 40417.4 42352.0 45386.8 45635.5 45717.8 62107.7 62222.4 63296.6 64238.7 64566.7 8762.1 8833.2 8849.5 8936.5 9108.2 13622.9 13677.5 13888.3 13930.9 13951.7 11330.2 11553.5 11559.2 11586.3 12315.3 10673.8 11037.5 11175.9 11251.9 11473.2 11736.2 12520.8 12530.6 12750.5 12805.6 8735.7 8941.4 9011.1 9131.6 9385.3 9095.1 11960.6 12017.8 12050.7 12306.6 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#3] mootools.html Cycles:180 Average:5723.41 Median:5080.38 stddev:3147.73 (62.0%) stddev-sans-first:3140.17 00:01:54 INFO - PROCESS | 29445 | Values: 1450.1 1453.1 1461.2 1477.4 1478.8 8234.1 8707.8 8740.7 8742.4 8752.2 7908.0 8156.3 8222.3 8356.5 8609.5 2392.5 2428.6 2430.5 2432.1 2449.6 2359.1 2374.8 2391.2 2397.5 2440.3 2377.4 2385.7 2404.6 2407.2 2408.0 2297.0 2342.3 2343.4 2373.7 2378.4 2314.9 2332.2 2382.0 2385.1 2389.4 8585.5 8670.6 8680.2 8680.4 8689.1 5311.9 5470.2 5471.7 5473.3 5475.5 3468.8 3528.6 3544.3 3567.6 3624.7 1346.8 1349.1 1358.3 1389.1 1421.0 12040.6 12051.2 12058.6 12076.2 12089.5 4616.6 4894.2 4912.7 4929.8 4936.6 10364.1 10378.6 10378.8 10387.6 10391.9 5579.2 5586.3 5592.3 5595.4 5597.2 4992.0 5044.2 5121.3 5125.3 5125.5 4008.2 4021.0 4051.9 4071.9 4099.5 1340.3 1347.7 1348.8 1354.9 1364.4 945.5 971.3 972.7 978.3 979.3 5573.9 5584.6 5638.0 5808.7 5810.2 1019.1 1038.4 1058.4 1063.6 1077.2 3858.3 3956.7 4020.8 4026.5 4099.9 10262.7 10448.5 10541.3 10664.7 10666.9 10394.2 10457.4 10493.1 10523.2 10530.7 8159.3 8344.3 8350.0 8365.3 8371.3 5645.4 5661.6 5664.9 5717.9 5798.7 9796.7 9815.9 9825.5 9866.5 9921.5 8991.8 9296.1 9776.5 9826.4 10094.6 6945.8 7093.4 7095.7 7119.8 7221.0 8132.6 8317.5 8358.7 8366.7 8586.0 7454.4 7475.0 7492.1 7677.4 7689.0 7249.5 7260.8 7418.6 7428.3 7478.6 6458.9 6574.5 6624.7 6755.8 6766.0 4973.7 5010.4 5117.4 5152.6 5176.0 4976.2 5116.6 5121.6 5205.6 5220.3 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#4] prototype.html Cycles:180 Average:4698.50 Median:5202.11 stddev:1328.88 (25.5%) stddev-sans-first:1292.77 00:01:54 INFO - PROCESS | 29445 | Values: 396.1 410.9 414.1 415.7 418.9 4991.8 5104.4 5240.4 5252.4 5273.0 5106.5 5134.8 5236.4 5241.7 5250.6 4165.5 4187.4 4194.0 4197.0 4198.7 4013.7 4068.5 4185.5 4207.2 4210.4 3983.6 4161.2 4175.9 4195.9 4200.9 4165.1 4170.4 4175.3 4184.3 4188.3 3811.2 3814.2 3815.7 3826.4 3828.9 4986.4 5130.0 5145.9 5161.9 5168.0 4901.3 4903.7 4911.3 4911.7 4930.1 5436.2 5575.4 5580.7 5581.4 5586.3 4179.6 4213.0 4215.5 4218.8 4230.9 5692.6 5706.0 5713.2 5724.5 5730.9 4360.6 4369.5 4370.2 4375.4 4391.7 4184.4 4417.5 4417.6 4456.3 4456.8 4891.2 4892.4 4892.9 4894.2 4894.9 5452.4 5600.5 5612.5 5622.6 5625.8 1438.7 1452.4 1462.6 1487.3 1494.4 1052.3 1081.5 1087.3 1090.8 1094.5 5173.9 5185.4 5216.6 5253.6 5283.1 5314.5 5477.3 5514.4 5541.8 5564.8 5156.8 5165.7 5176.8 5180.1 5187.6 2880.4 2891.9 2918.6 2921.0 2926.3 5558.7 5671.9 5703.9 5878.9 5920.0 5783.4 5796.5 5803.8 5815.8 5820.7 5733.6 5853.2 5881.8 5895.8 5908.2 5584.4 5630.2 5650.2 5676.7 5682.7 5623.6 5638.3 5649.1 5661.5 5673.1 5684.8 5841.5 5869.8 5903.3 5904.1 4117.9 4125.5 4127.0 4159.9 4161.4 5675.5 5679.5 5684.2 5689.4 5728.8 5301.0 5527.9 5546.4 5547.9 5572.0 5497.9 5539.9 5556.6 5562.6 5568.3 5265.7 5359.3 5443.0 5533.6 5542.6 5528.8 5557.3 5604.9 5605.7 5613.5 5231.6 5433.4 5438.2 5444.0 5459.8 00:01:54 INFO - PROCESS | 29445 | 00:01:54 INFO - PROCESS | 29445 | [#5] yui.html Cycles:180 Average:17803.56 Median:4369.78 stddev:22081.63 (505.3%) stddev-sans-first:22106.43 00:01:54 INFO - PROCESS | 29445 | Values: 747.3 751.5 788.6 859.9 898.6 14725.9 16368.3 16684.4 16719.6 16739.8 15365.0 16027.2 16028.4 16031.2 16049.7 580.3 594.7 734.4 745.8 763.4 749.1 763.1 766.1 767.9 769.9 803.7 803.8 804.1 804.3 804.4 672.9 690.9 764.2 800.5 800.7 578.8 582.6 582.8 583.2 583.4 16179.2 16250.5 16318.6 16337.8 16369.3 15039.0 15486.6 15844.0 15983.1 16063.0 13567.0 13674.3 13826.9 13924.9 13931.3 4589.6 4619.3 4622.4 4650.2 4670.3 80712.0 82177.2 83299.0 83413.2 83511.0 12031.4 12079.6 12101.4 12194.7 12320.7 23406.4 23595.3 23792.6 23807.8 23943.9 8179.2 8375.8 8491.8 8501.8 8775.1 4335.4 4404.1 4434.5 4479.0 4488.0 3485.1 3540.6 3566.8 3570.1 3579.4 648.4 664.7 667.8 669.4 677.0 781.9 785.8 811.1 811.4 846.0 10269.7 10960.2 11244.3 11532.0 11575.4 764.2 791.1 801.3 808.1 817.8 5195.4 5374.2 5398.4 5404.6 5425.8 78257.6 79389.0 82059.0 82322.0 84136.0 56482.7 59284.7 71305.3 73342.6 73821.3 57183.7 57420.1 57438.0 58045.7 59316.4 10716.7 11176.5 11482.9 11569.3 11570.7 38554.2 38861.0 39460.4 40225.4 40329.4 45391.3 48115.8 48527.8 49580.3 51090.7 13436.1 13484.9 13609.9 13725.1 13791.9 15247.5 15442.4 15460.3 15489.2 15520.9 13821.9 14011.8 14543.0 14650.9 15142.9 13538.5 13861.7 13975.7 14551.6 14671.0 13749.1 13869.9 13966.8 14194.4 14207.4 11207.7 11546.8 11767.1 12080.6 12096.3 11594.4 11616.4 11770.0 11772.4 11820.8 00:01:54 INFO - PROCESS | 29445 | -------- Summary: end -------- 00:01:54 INFO - PROCESS | 29445 | 00:01:55 INFO - TEST-INFO | 29445: exit 0 00:01:55 INFO - TEST-OK | dromaeo_css | took 1114868ms 00:01:55 INFO - TEST-START | kraken 00:01:55 INFO - operating with platform_type : linux_ 00:01:55 INFO - Initialising browser for kraken test... 00:01:55 INFO - TEST-INFO | started process 29748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp88ua3c/profile http://localhost:44920/getInfo.html) 00:01:59 INFO - PROCESS | 29748 | __metrics Screen width/height:1600/1200 00:01:59 INFO - PROCESS | 29748 | colorDepth:24 00:01:59 INFO - PROCESS | 29748 | Browser inner width/height: 1024/697 00:01:59 INFO - PROCESS | 29748 | __metrics 00:01:59 INFO - PROCESS | 29748 | 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" 00:01:59 INFO - PROCESS | 29748 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:02:00 INFO - PROCESS | 29748 | console.error: PushService: 00:02:00 INFO - PROCESS | 29748 | stateChangeProcessEnqueue: Error transitioning state 00:02:00 INFO - PROCESS | 29748 | UnknownError 00:02:00 INFO - TEST-INFO | 29748: exit 0 00:02:00 INFO - Browser initialized. 00:02:00 INFO - Running cycle 1/1 for kraken test... 00:02:01 INFO - TEST-INFO | started process 29831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp88ua3c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:02:12 INFO - PROCESS | 29831 | RSS: Main: 147423232 00:02:12 INFO - PROCESS | 29831 | 00:02:15 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44920/tests/kraken/driver.html?testName=audio-beat-detection) 00:02:16 INFO - PROCESS | 29831 | RSS: Main: 203292672 00:02:16 INFO - PROCESS | 29831 | 00:02:18 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44920/tests/kraken/driver.html?testName=audio-dft) 00:02:19 INFO - PROCESS | 29831 | RSS: Main: 181325824 00:02:19 INFO - PROCESS | 29831 | 00:02:22 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44920/tests/kraken/driver.html?testName=audio-fft) 00:02:22 INFO - PROCESS | 29831 | RSS: Main: 263098368 00:02:22 INFO - PROCESS | 29831 | 00:02:25 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44920/tests/kraken/driver.html?testName=audio-oscillator) 00:02:25 INFO - PROCESS | 29831 | RSS: Main: 176185344 00:02:25 INFO - PROCESS | 29831 | 00:02:27 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44920/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:02:28 INFO - PROCESS | 29831 | RSS: Main: 179359744 00:02:28 INFO - PROCESS | 29831 | 00:02:31 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44920/tests/kraken/driver.html?testName=imaging-darkroom) 00:02:31 INFO - PROCESS | 29831 | RSS: Main: 218189824 00:02:31 INFO - PROCESS | 29831 | 00:02:35 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44920/tests/kraken/driver.html?testName=imaging-desaturate) 00:02:35 INFO - PROCESS | 29831 | RSS: Main: 217661440 00:02:35 INFO - PROCESS | 29831 | 00:02:38 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44920/tests/kraken/driver.html?testName=json-parse-financial) 00:02:39 INFO - PROCESS | 29831 | RSS: Main: 216424448 00:02:39 INFO - PROCESS | 29831 | 00:02:40 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44920/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:02:41 INFO - PROCESS | 29831 | RSS: Main: 178995200 00:02:41 INFO - PROCESS | 29831 | 00:02:43 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:02:43 INFO - PROCESS | 29831 | RSS: Main: 186331136 00:02:43 INFO - PROCESS | 29831 | 00:02:45 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:02:46 INFO - PROCESS | 29831 | RSS: Main: 194359296 00:02:46 INFO - PROCESS | 29831 | 00:02:49 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:02:49 INFO - PROCESS | 29831 | RSS: Main: 199782400 00:02:49 INFO - PROCESS | 29831 | 00:02:52 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:02:53 INFO - PROCESS | 29831 | RSS: Main: 200208384 00:02:53 INFO - PROCESS | 29831 | 00:02:54 INFO - PROCESS | 29831 | Cycle 1(1): loaded http://localhost:44920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44920/tests/kraken/driver.html?testName=ai-astar) 00:02:55 INFO - PROCESS | 29831 | RSS: Main: 252059648 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | __start_tp_report 00:02:55 INFO - PROCESS | 29831 | _x_x_mozilla_page_load 00:02:55 INFO - PROCESS | 29831 | _x_x_mozilla_page_load_details 00:02:55 INFO - PROCESS | 29831 | |i|pagename|runs| 00:02:55 INFO - PROCESS | 29831 | |0;ai-astar;109;110;111;109;110;110;110;111;109;109 00:02:55 INFO - PROCESS | 29831 | |1;audio-beat-detection;146;120;120;159;122;122;121;121;150;121 00:02:55 INFO - PROCESS | 29831 | |2;audio-dft;160;159;160;160;160;160;161;161;161;160 00:02:55 INFO - PROCESS | 29831 | |3;audio-fft;75;75;75;108;77;75;76;76;91;76 00:02:55 INFO - PROCESS | 29831 | |4;audio-oscillator;83;83;115;83;83;85;98;84;84;108 00:02:55 INFO - PROCESS | 29831 | |5;imaging-gaussian-blur;116;117;116;117;117;117;117;117;117;117 00:02:55 INFO - PROCESS | 29831 | |6;imaging-darkroom;146;147;146;146;146;146;147;146;146;146 00:02:55 INFO - PROCESS | 29831 | |7;imaging-desaturate;96;96;96;96;96;96;96;95;96;95 00:02:55 INFO - PROCESS | 29831 | |8;json-parse-financial;53;53;52;52;52;53;54;53;52;53 00:02:55 INFO - PROCESS | 29831 | |9;json-stringify-tinderbox;66;67;66;67;67;67;66;67;67;67 00:02:55 INFO - PROCESS | 29831 | |10;stanford-crypto-aes;82;84;82;84;82;81;81;82;81;80 00:02:55 INFO - PROCESS | 29831 | |11;stanford-crypto-ccm;126;127;134;126;139;126;125;126;203;138 00:02:55 INFO - PROCESS | 29831 | |12;stanford-crypto-pbkdf2;180;171;172;171;171;170;171;168;168;173 00:02:55 INFO - PROCESS | 29831 | |13;stanford-crypto-sha256-iterative;65;63;63;64;66;62;63;64;63;62 00:02:55 INFO - PROCESS | 29831 | __end_tp_report 00:02:55 INFO - PROCESS | 29831 | __start_cc_report 00:02:55 INFO - PROCESS | 29831 | _x_x_mozilla_cycle_collect,1550 00:02:55 INFO - PROCESS | 29831 | __end_cc_report 00:02:55 INFO - PROCESS | 29831 | __startTimestamp1484640175087__endTimestamp 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | ------- Summary: start ------- 00:02:55 INFO - PROCESS | 29831 | Number of tests: 14 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 00:02:55 INFO - PROCESS | 29831 | Values: 109.0 110.0 111.0 109.0 110.0 110.0 110.0 111.0 109.0 109.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:122.00 stddev:15.16 (12.4%) stddev-sans-first:14.96 00:02:55 INFO - PROCESS | 29831 | Values: 146.0 120.0 120.0 159.0 122.0 122.0 121.0 121.0 150.0 121.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 00:02:55 INFO - PROCESS | 29831 | Values: 160.0 159.0 160.0 160.0 160.0 160.0 161.0 161.0 161.0 160.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:10.85 (14.3%) stddev-sans-first:11.34 00:02:55 INFO - PROCESS | 29831 | Values: 75.0 75.0 75.0 108.0 77.0 75.0 76.0 76.0 91.0 76.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#4] audio-oscillator Cycles:10 Average:90.60 Median:83.50 stddev:12.03 (14.4%) stddev-sans-first:12.44 00:02:55 INFO - PROCESS | 29831 | Values: 83.0 83.0 115.0 83.0 83.0 85.0 98.0 84.0 84.0 108.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 00:02:55 INFO - PROCESS | 29831 | Values: 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 00:02:55 INFO - PROCESS | 29831 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 00:02:55 INFO - PROCESS | 29831 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 00:02:55 INFO - PROCESS | 29831 | Values: 53.0 53.0 52.0 52.0 52.0 53.0 54.0 53.0 52.0 53.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 00:02:55 INFO - PROCESS | 29831 | Values: 66.0 67.0 66.0 67.0 67.0 67.0 66.0 67.0 67.0 67.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:82.00 stddev:1.29 (1.6%) stddev-sans-first:1.36 00:02:55 INFO - PROCESS | 29831 | Values: 82.0 84.0 82.0 84.0 82.0 81.0 81.0 82.0 81.0 80.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:130.50 stddev:23.80 (18.2%) stddev-sans-first:24.91 00:02:55 INFO - PROCESS | 29831 | Values: 126.0 127.0 134.0 126.0 139.0 126.0 125.0 126.0 203.0 138.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:171.00 stddev:3.37 (2.0%) stddev-sans-first:1.67 00:02:55 INFO - PROCESS | 29831 | Values: 180.0 171.0 172.0 171.0 171.0 170.0 171.0 168.0 168.0 173.0 00:02:55 INFO - PROCESS | 29831 | 00:02:55 INFO - PROCESS | 29831 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.27 (2.0%) stddev-sans-first:1.22 00:02:55 INFO - PROCESS | 29831 | Values: 65.0 63.0 63.0 64.0 66.0 62.0 63.0 64.0 63.0 62.0 00:02:55 INFO - PROCESS | 29831 | -------- Summary: end -------- 00:02:55 INFO - PROCESS | 29831 | 00:02:56 INFO - TEST-INFO | 29831: exit 0 00:02:56 INFO - TEST-OK | kraken | took 60778ms 00:02:56 INFO - SUITE-END | took 1175s 00:02:56 INFO - Completed test suite (00:19:35) 00:02:56 INFO - javascript benchmark 00:02:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3194.680531946806, 3205.022468147214, 3211.341674956634, 3221.6295126060495, 3263.918402039949, 5239.240310155034, 5273.367195936484, 5344.973275133624, 5440.5375543078835, 5457.099578569536, 5357.303550538434, 5365.222042803791, 5368.570514358852, 5378.946210537893, 5379.300690910186, 4752.572268495844, 4774.2122549779415, 4800.87997800056, 4846.4911184325665, 4895.8776030599165, 4877.0, 4889.071076495472, 4890.77991490382, 4920.901581968376, 5000.799968001275, 4967.10592093423, 4977.129002424579, 4977.203647416414, 4978.054169707744, 5016.724080175589, 405.4539847051125, 4814.398200224972, 4828.855134345975, 4909.26361045846, 4970.776315065813, 4864.0028794097125, 4872.634552408548, 4873.269009648581, 4876.634252431082, 4909.337239472661, 5381.0583147948955, 5382.273393091961, 5386.542143917772, 5426.968875913563, 5432.429594892558, 5566.749496272678, 5582.553395728332, 5691.9146212806845, 5794.101914203306, 5866.002779527491, 5779.422057794229, 5783.334916484574, 5825.941740582624, 5885.61743486672, 5887.911681324784, 5419.970205660912, 5421.322334708161, 5425.945740542623, 5427.83716488506, 5430.864228394283, 5703.287089113861, 5734.942650573525, 5947.078202878561, 5952.583319167703, 5962.165296858443, 5566.610337276433, 5586.832395028156, 5599.440055994368, 5640.210370548127, 5680.715964201773, 5703.486686198262, 5704.71476426177, 5705.885882282414, 5706.657600543897, 5711.314642242957, 5530.695811730394, 5633.070543360382, 5672.546196304285, 5686.289213848269, 5693.857653558694, 5566.610337276433, 5661.065924122474, 5670.716464176775, 5704.058830293038, 5790.594658373958, 4486.484054333793, 4489.7979590918485, 4495.595396414273, 4496.707713998645, 4532.388127602798, 3928.6071392860486, 3957.445957565943, 4002.7198096133575, 4012.378081397388, 4046.069404037088, 5401.675899445967, 5454.318210223722, 5490.478404551562, 5520.089185284383, 5536.474034966672, 5834.883302334015, 5877.23595932526, 5880.176775251468, 5892.057270836645, 5916.556258280646, 5488.451154884479, 5496.972515137399, 5499.092649712753, 5502.0921547944945, 5509.421510741395, 4787.210110331756, 4832.710037397767, 4971.105201063771, 5011.1481048221885, 5063.8480845574695, 6010.459058684739, 6118.101457354478, 6118.293648225375, 6118.4059889896, 6123.069106197231, 5867.237259156283, 5881.911771323348, 5884.35272120075, 5889.5582831286965, 5900.527957763283, 5854.590178687621, 6011.098335249748, 6064.514838812973, 6077.058056001327, 6084.695765211633, 5581.609287349846, 5610.6072574921, 5639.492445679908, 5670.886582268414, 5775.1914731938405, 5759.94240057594, 5798.01433756254, 5799.159121927212, 5814.447627475383, 5814.912776308443, 5643.689597072201, 5671.801486948102, 5675.716214189191, 5677.176809362537, 5679.488846003879, 5508.476694713996, 5508.504234618904, 5638.0, 5661.122526008476, 5723.427657234243, 5739.426057394227, 5741.827745167651, 5754.769809207753, 5757.8560535986935, 5776.306843178845, 5772.624779389326, 5802.970985145047, 5803.7968671096305, 5804.477597016289, 5806.390329015561, 5364.436734143015, 5397.7840886365675, 5594.4125866785025, 5655.038643430545, 5667.3482549507335], "value": 5303.0597758617205, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.0006901046658, 583.7373182068069, 605.391581460632, 632.4213344789517, 653.3009679642784, 33709.0, 45000.54999450005, 46717.29924051142, 46803.76598117013, 46939.530604693944, 47358.0, 47701.7614911925, 47744.52255477444, 47796.0, 47800.52199478004, 8618.439801412887, 8935.463872167658, 9168.679096231634, 9174.266058715286, 9178.403403778766, 8615.526146061964, 10719.517621707042, 10945.0, 10948.45257737114, 11296.378699171584, 10966.835497467544, 11031.944840275828, 11042.33745975244, 11047.281926674781, 11050.226484146113, 10895.128389728803, 10936.234463587552, 10955.83566246507, 10956.397398143099, 10965.451727413638, 10780.191485638608, 10851.565937362497, 10871.83692244617, 10872.619458319, 10876.18428617849, 44450.77774611139, 44747.0, 44795.77602111969, 44827.0, 44905.77547112276, 4249.6387807036435, 4295.806688699016, 4342.6308763754805, 4439.733615983051, 4484.1928452878465, 32772.6722732772, 33215.0, 33242.3351532968, 33604.0, 35025.29949401023, 4311.719738217006, 4403.097365040191, 4474.373587697725, 4476.709013914117, 4499.415076040126, 327905.36047319847, 332454.0, 332459.0, 333022.0, 333184.0, 63857.36142638607, 63977.0, 64017.0, 64293.0, 64379.67810160919, 66685.0, 66918.66540667362, 67071.32928670748, 67107.66446167738, 67233.32766672368, 17038.06290654026, 17534.386296479563, 17541.210645520983, 17542.0, 17543.912280438515, 12583.11918165738, 13112.147710398793, 13479.326033698277, 13672.31638418075, 13760.243186624632, 18373.0, 18950.526236843914, 19174.424767257005, 19295.710564341545, 19336.4199074028, 6599.637019963948, 6657.567258128206, 6734.730610775514, 6837.726490940406, 6903.896441553381, 941.3081385181886, 946.0208684012015, 959.52023988006, 966.4104896013426, 998.1366118307611, 31419.685803142132, 32398.0, 32413.18967025796, 32546.511802322984, 32704.945270226875, 1057.1595581512715, 1096.7696783675517, 1104.3886491173816, 1123.25864929148, 1138.5502726423183, 16142.757858631894, 17261.223244954006, 17324.740128898076, 17420.47738567845, 17474.65050698979, 449667.0, 449982.0, 451228.0, 453391.0, 457546.0, 62838.37161628417, 63080.36919630745, 63097.68451157807, 63181.36818631847, 63270.68364658147, 1463.5463006468028, 1468.7062587482546, 1476.8670819626286, 1479.963000924964, 1510.5241848817807, 29646.555301670924, 31743.841280793447, 32045.519317209793, 32114.678853211168, 32697.34605307833, 51728.0, 53257.733711331195, 53330.0, 53378.73310633422, 53407.7329613365, 355109.0, 358546.0, 359021.0, 359538.0, 360536.0, 41520.58479415288, 41699.37450938299, 41826.516889882114, 42013.15973680448, 42319.7884010578, 45597.31604025876, 46676.53323466722, 47158.29262560996, 47282.0, 47454.0, 38844.80577597094, 39174.216515670094, 39256.0, 39821.2035759289, 41372.37941430941, 38057.23885522219, 38493.4225986616, 38829.22341553096, 40036.0, 40103.3984490227, 39712.0, 40351.0, 40852.79573602113, 40951.7952410236, 41175.17649646931, 32600.51099233466, 34436.311273773885, 35110.29779404448, 35178.82410587931, 35350.82324588361, 26384.340391489597, 27041.86479067592, 27094.94981679127, 27113.86443067772, 27323.0], "value": 20899.603961626413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.69717291125045, 410.4049128763292, 427.93153095504715, 428.1608048225478, 441.1683975705794, 15773.526794196163, 19926.800731992695, 21296.46758831028, 21400.89299553504, 21527.784722152774, 21163.682544761803, 21803.890980545115, 21947.67078493824, 22153.44616384591, 22213.888930555364, 5818.854528636797, 5836.387179346171, 5915.319738230116, 6080.2399700037495, 6086.847828804271, 5856.0630299151935, 5956.583039187259, 5961.135635332874, 5962.0162673158875, 5967.254093238345, 5687.0, 5820.941790582104, 5821.359650438449, 5922.845045216184, 5929.555283353766, 5851.4148585141575, 5887.705614719247, 5935.10973353996, 5937.643741375509, 5940.821775346725, 5198.454162312941, 5233.4243233244315, 5267.26258323835, 5268.341457317835, 5276.287701160332, 20338.0, 20445.488862778402, 23988.52022959531, 24351.0, 24503.632445513333, 3003.684613115613, 3061.0969763919697, 3073.800202986821, 3093.721565059133, 3161.668024857403, 15042.32309546073, 16541.66916661672, 16700.74948875768, 16901.154942252837, 17294.65410691779, 2172.6089303925287, 2207.9889600551896, 2228.5097278598682, 2242.9887850560644, 2293.598620241468, 103054.48472757588, 105345.47327263391, 105406.47296763543, 106402.46798765956, 108231.0, 15130.773038404433, 18988.62022759537, 19507.51231219231, 19522.511937201685, 19844.503887402643, 34036.829815851255, 36900.81549592235, 37042.62957370446, 37172.0, 37347.25305493875, 10503.479165000344, 10586.629467968583, 10590.788184236271, 10841.783164336826, 10943.179261555415, 8620.396572240008, 8717.128287171232, 8737.126287371211, 8920.8661870072, 8956.0, 10044.598216071421, 10170.745731356776, 10227.59089636421, 10266.6406675766, 10342.37945723259, 3749.9062523437133, 3897.395903634941, 3899.454076429302, 4093.488313960755, 4170.374443833449, 881.2465342132513, 885.6545947080643, 888.9777755556163, 895.8924929008431, 902.9210392561334, 19039.0, 19780.20879164846, 20408.28571000008, 21009.579808403745, 21128.577428451652, 893.6595107338992, 923.9907600923905, 936.8735220745249, 937.6062053937362, 940.9623615055458, 11025.83461248082, 11374.374409407397, 11559.537140724151, 11648.068154547616, 11666.416679166008, 540646.0, 550322.0, 564363.0, 567740.0, 579133.0, 99017.50491247498, 100500.0, 102679.0, 103394.48302758439, 104892.0, 1045.1743122932799, 1049.480507148951, 1114.5207560748793, 1118.3066498770813, 1118.4351902289454, 18539.907300463412, 18554.907225463787, 18817.811821881605, 18819.0, 18874.52813679614, 40417.39373909453, 42352.0, 45386.77306613578, 45635.54364456446, 45717.771411142734, 62107.6894615524, 62222.377776221656, 63296.56924015275, 64238.67880660567, 64566.677166615744, 8762.123787621187, 8833.249173820297, 8849.469031857852, 8936.508492033001, 9108.180263776027, 13622.86377136216, 13677.452901883813, 13888.305584720925, 13930.930345348208, 13951.720965580429, 11330.206885517935, 11553.537858485566, 11559.248648837798, 11586.304821710724, 12315.322657253937, 10673.839892401775, 11037.503312351124, 11175.944120279346, 11251.887481125084, 11473.196876218584, 11736.237144585575, 12520.812187817372, 12530.561430349893, 12750.489980401051, 12805.55180568713, 8735.65057397697, 8941.37410381267, 9011.098890110936, 9131.634734610807, 9385.343025988115, 9095.090490950852, 11960.58137965167, 12017.75964480688, 12050.698732531406, 12306.630801075982], "value": 10679.337862495355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.08644553931, 1453.0990785712859, 1461.1963420118932, 1477.3573495529452, 1478.770790527468, 8234.094249632542, 8707.782305442359, 8740.737777866674, 8742.388032837704, 8752.212300892917, 7907.960460197691, 8156.347492200624, 8222.34221262299, 8356.456830306026, 8609.526476043815, 2392.521495700858, 2428.623563347675, 2430.513897220554, 2432.1487479382254, 2449.5590793657134, 2359.0563774490256, 2374.7862692357685, 2391.175044609607, 2397.5444665513583, 2440.2923152285816, 2377.405648587853, 2385.689860318156, 2404.603240465321, 2407.181558270188, 2407.9759202408013, 2297.0, 2342.3090188394463, 2343.3907184132077, 2373.655819906121, 2378.3935096550354, 2314.895829687668, 2332.2187067332466, 2381.9523609527887, 2385.1294277588554, 2389.390705370137, 8585.484870907703, 8670.609822558, 8680.218780309802, 8680.435771674822, 8689.131086891324, 5311.893762124736, 5470.234167216553, 5471.699056551888, 5473.27591808579, 5475.479829416199, 3468.843902024415, 3528.5942116656656, 3544.3442963051916, 3567.625399333059, 3624.655657712513, 1346.838379394469, 1349.0893646788477, 1358.2733237717773, 1389.0970868935171, 1420.9857901420958, 12040.578579749754, 12051.15641905067, 12058.6382408527, 12076.21504602198, 12089.455974481169, 4616.630669546428, 4894.19245824435, 4912.6561140719805, 4929.7535123244415, 4936.6297527685565, 10364.067233948981, 10378.636747713794, 10378.844317335246, 10387.584496620202, 10391.896081039093, 5579.246801681802, 5586.329640443091, 5592.273004509388, 5595.440455954372, 5597.216389705444, 4991.950080499221, 5044.1677123274985, 5121.334226550524, 5125.333706618116, 5125.487451254845, 4008.158286759755, 4021.0286329945466, 4051.918961620751, 4071.898202544901, 4099.467069280974, 1340.2628554295293, 1347.7371912476972, 1348.7774517204748, 1354.939027743754, 1364.379207460603, 945.4705364995624, 971.3055165556519, 972.6644307713827, 978.2711879649706, 979.3487330924997, 5573.860653483614, 5584.636998595079, 5637.9718101409235, 5808.651480911159, 5810.157527158538, 1019.1439191079529, 1038.3665963762098, 1058.4178701714181, 1063.5958335832488, 1077.2405454154803, 3858.3055050090693, 3956.663683586927, 4020.819063142107, 4026.516817981861, 4099.8975025624595, 10262.692119236435, 10448.477576121164, 10541.31481453703, 10664.733381665521, 10666.893331066745, 10394.22043346752, 10457.42484163363, 10493.055624993787, 10523.158147348193, 10530.736731581772, 8159.347252219808, 8344.290735287446, 8349.958250208832, 8365.288950439159, 8371.288440482505, 5645.40723224072, 5661.603687741818, 5664.85837854057, 5717.914231286451, 5798.710064496674, 9796.706098817047, 9815.920248772773, 9825.459599722084, 9866.506674666382, 9921.503924803586, 8991.775205619912, 9296.116868897443, 9776.462294573867, 9826.361286516354, 10094.646687365906, 6945.756898508528, 7093.432525398059, 7095.7293666028445, 7119.750808721877, 7221.025161603116, 8132.593370331341, 8317.542535160625, 8358.749237522885, 8366.66533338684, 8586.0, 7454.366378857858, 7474.962625186839, 7492.100947886289, 7677.424193185645, 7688.961555192188, 7249.528780629243, 7260.782176534713, 7418.554886706815, 7428.325802711128, 7478.551286922802, 6458.903116453351, 6574.506911981716, 6624.668766561749, 6755.763548275983, 6765.9661701691175, 4973.651844370859, 5010.398752149766, 5117.360329958755, 5152.613553983391, 5175.9741201293755, 4976.2286845539, 5116.590672746097, 5121.590272778094, 5205.6356055075785, 5220.347456567929], "value": 4658.538122305506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.1008510680755, 410.8705757686329, 414.0745433955108, 415.6716194206577, 418.8680565621827, 4991.775370108345, 5104.3619547556555, 5240.44975277596, 5252.395974462938, 5272.973635131819, 5106.540411362977, 5134.768935397907, 5236.423993360727, 5241.737913104349, 5250.579953603712, 4165.458490396252, 4187.350960601112, 4193.9580604194025, 4197.0, 4198.706090573661, 4013.6588389986887, 4068.490415335469, 4185.539590645027, 4207.15856828634, 4210.3894935234375, 3983.6016398360075, 4161.169085602341, 4175.916481670365, 4195.916081678364, 4200.873973780776, 4165.062860856298, 4170.374443833419, 4175.331946888514, 4184.3305071188615, 4188.32986722123, 3811.161544460242, 3814.1799513104816, 3815.7138214633737, 3826.4069069294305, 3828.885133446001, 4986.426560945465, 5129.974350128263, 5145.897082058338, 5161.896762064775, 5167.974160129213, 4901.338319326881, 4903.681260718042, 4911.263310503417, 4911.705297682151, 4930.112579735646, 5436.184572314145, 5575.414581468928, 5580.651609034588, 5581.358143813432, 5586.329640443132, 4179.623833854937, 4212.957870421318, 4215.494140703105, 4218.810153543098, 4230.936535951963, 5692.6299790513485, 5706.0, 5713.171590119409, 5724.4561766632105, 5730.856728581777, 4360.563943605614, 4369.531214863592, 4370.2352088384405, 4375.387445757597, 4391.67062470316, 4184.422318917343, 4417.469903611587, 4417.646588272931, 4456.3288766712485, 4456.844010459683, 4891.1685013547785, 4892.412910450768, 4892.902141957212, 4894.241392584155, 4894.853154405374, 5452.427495112956, 5600.459873534811, 5612.466815652507, 5622.57830662702, 5625.774969001276, 1438.6547228665045, 1452.375478544236, 1462.6343414146463, 1487.315834716021, 1494.3798323695544, 1052.2529004406956, 1081.4700796609761, 1087.260662749338, 1090.8091084060259, 1094.5457635081511, 5173.870653233624, 5185.377754669388, 5216.608754343363, 5253.55344795689, 5283.101872681654, 5314.468553144655, 5477.281168750774, 5514.448555144453, 5541.838682913667, 5564.805231816867, 5156.793728250828, 5165.74171291434, 5176.757578181209, 5180.119379705459, 5187.610929180327, 2880.438314528689, 2891.8843246270335, 2918.599072445196, 2920.956185657217, 2926.341573146025, 5558.666480011212, 5671.8865622688145, 5703.857403564944, 5878.941210587925, 5919.968027176951, 5783.351744752726, 5796.47831695141, 5803.796867109715, 5815.82552523425, 5820.70896455184, 5733.627314224562, 5853.209816674779, 5881.7647294107755, 5895.793647222326, 5908.231929849178, 5584.413636568264, 5630.211770352236, 5650.208970744181, 5676.672564248638, 5682.659040457585, 5623.634463759842, 5638.323401191883, 5649.067903795828, 5661.537231304395, 5673.092305231144, 5684.8010319638615, 5841.474267315792, 5869.765209391577, 5903.291605007426, 5904.0553511437965, 4117.876463706094, 4125.546189919167, 4126.958730412657, 4159.916801664011, 4161.4382058421725, 5675.531143952394, 5679.460451257124, 5684.232628595087, 5689.402612725604, 5728.799492017926, 5301.0, 5527.917081243704, 5546.36216835053, 5547.944520554905, 5571.9721401392735, 5497.890042199214, 5539.861503462445, 5556.638818476786, 5562.5549956002615, 5568.2761241040025, 5265.7367131642495, 5359.276497672764, 5443.047466693233, 5533.5849811265125, 5542.556595472433, 5528.75120619565, 5557.277553917965, 5604.943950560442, 5605.711630602674, 5613.494785469164, 5231.55531779803, 5433.359649035235, 5438.211459338453, 5443.972780136232, 5459.836204913859], "value": 4302.569891150559, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.2826086956518, 751.4739682222446, 788.6135793461206, 859.8597751824964, 898.564196364763, 14725.926370368146, 16368.345266189353, 16684.41604543841, 16719.58201044973, 16739.83260167398, 15365.0, 16027.198640068009, 16028.35886564536, 16031.198440078007, 16049.679006419894, 580.2659635561018, 594.6937327276435, 734.3537686835577, 745.79490640074, 763.427429427929, 749.0973377080632, 763.1224092293878, 766.0768773627793, 767.8502691975067, 769.86142494351, 803.7106641609016, 803.7869964459423, 804.0793291681022, 804.2721337189823, 804.4087595617217, 672.9327067293256, 690.9309069093101, 764.182324912344, 800.4996876951905, 800.6957356204634, 578.7627072900133, 582.6416753696489, 582.7872826418369, 583.1689841975168, 583.3990989281004, 16179.191040447931, 16250.512484625362, 16318.592035199097, 16337.754933676004, 16369.345226190939, 15039.0, 15486.61283467911, 15844.0, 15983.120928348937, 16063.036217826968, 13567.0, 13674.31628418585, 13826.861731382658, 13924.93037534816, 13931.30343482832, 4589.577275777237, 4619.307103934403, 4622.399088118556, 4650.162970665313, 4670.299455081731, 80712.0, 82177.17822821756, 83298.99999999939, 83413.16586834175, 83511.0, 12031.398430078636, 12079.57721479744, 12101.394930253453, 12194.695132621755, 12320.691982700504, 23406.414839629146, 23595.29214123579, 23792.643110353358, 23807.761922380556, 23943.880280598485, 8179.248412120772, 8375.811397395544, 8491.830163396697, 8501.787455313666, 8775.078616745277, 4335.414719012956, 4404.141192467502, 4434.467863856294, 4479.037007043501, 4488.0, 3485.1112966193964, 3540.5751309842985, 3566.7859928404378, 3570.125319296737, 3579.4451859961746, 648.4423395879539, 664.6643445060214, 667.8263651450658, 669.4343279928453, 677.0487465111527, 781.9218078192135, 785.7878372839301, 811.1401913971209, 811.4401063266328, 846.0481957797477, 10269.7432564185, 10960.177986651033, 11244.325340479598, 11532.0, 11575.363355015555, 764.1632412508294, 791.1416113516783, 801.254833005299, 808.1191501263651, 817.7832874288318, 5195.350581177353, 5374.1938709193155, 5398.433164517748, 5404.567634589223, 5425.810096646598, 78257.60871195607, 79389.0, 82059.0, 82322.0, 84136.0, 56482.7175864118, 59284.70357648184, 71305.2869471309, 73342.63328683429, 73821.26178738145, 57183.71408142932, 57420.13869791957, 57438.0, 58045.70977145087, 59316.40683593109, 10716.73208169802, 11176.497057632589, 11482.94258528702, 11569.305841649528, 11570.652880413601, 38554.22891542209, 38861.0, 39460.40809387919, 40225.396619051324, 40329.39505907355, 45391.31913021241, 48115.75942120267, 48527.75736121297, 49580.25629615631, 51090.74454627852, 13436.126651767603, 13484.86515134836, 13609.931950340186, 13725.107867988549, 13791.931040344734, 15247.466338678092, 15442.382304708135, 15460.304286307368, 15489.225538722794, 15520.940396954415, 13821.861781382058, 14011.789823152865, 14543.0, 14650.853491465376, 15142.924285378502, 13538.458461661423, 13861.722765544835, 13975.650608734863, 14551.636209094433, 14671.0, 13749.106308089942, 13869.86130138726, 13966.790498142333, 14194.3612537434, 14207.360668770138, 11207.693730349403, 11546.826797597874, 11767.11746619024, 12080.577179798665, 12096.274223546616, 11594.362310073027, 11616.361100139573, 11770.0, 11772.411379431165, 11820.763584728085], "value": 7476.491879296323, "unit": "score"}], "name": "dromaeo_css", "value": 7495.870106659242, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 111.0, 109.0, 110.0, 110.0, 110.0, 111.0, 109.0, 109.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 120.0, 159.0, 122.0, 122.0, 121.0, 121.0, 150.0, 121.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 161.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 108.0, 77.0, 75.0, 76.0, 76.0, 91.0, 76.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 115.0, 83.0, 83.0, 85.0, 98.0, 84.0, 84.0, 108.0], "value": 90.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 52.0, 52.0, 53.0, 54.0, 53.0, 52.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 84.0, 82.0, 81.0, 81.0, 82.0, 81.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 134.0, 126.0, 139.0, 126.0, 125.0, 126.0, 203.0, 138.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 171.0, 172.0, 171.0, 171.0, 170.0, 171.0, 168.0, 168.0, 173.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 64.0, 66.0, 62.0, 63.0, 64.0, 63.0, 62.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1503.3000000000002, "alertThreshold": 2.0}]} 00:02:56 INFO - Return code: 0 00:02:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:02:56 ERROR - # TBPL SUCCESS # 00:02:56 INFO - Running post-action listener: _resource_record_post_action 00:02:56 INFO - [mozharness: 2017-01-17 08:02:56.393218Z] Finished run-tests step (success) 00:02:56 INFO - Running post-run listener: _resource_record_post_run 00:02:56 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402501632; Read time: 12; Write time: 233808 00:02:56 INFO - TinderboxPrint: CPU usage
13.0% 00:02:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 00:02:56 INFO - TinderboxPrint: I/O write bytes / time
402,501,632 / 233,808 00:02:56 INFO - TinderboxPrint: CPU idle
8,279.5 (86.9%) 00:02:56 INFO - TinderboxPrint: CPU user
1,195.6 (12.6%) 00:02:56 INFO - TinderboxPrint: Swap in / out
0 / 0 00:02:56 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106000384; Read time: 0; Write time: 64320 00:02:56 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296501248; Read time: 12; Write time: 169488 00:02:57 INFO - Running post-run listener: _upload_blobber_files 00:02:57 INFO - Blob upload gear active. 00:02:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:02:57 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 00:02:57 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'] 00:02:57 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 00:02:57 INFO - (blobuploader) - INFO - Open directory for files ... 00:02:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:02:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:58 INFO - (blobuploader) - INFO - Done attempting. 00:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:02:58 INFO - (blobuploader) - INFO - Done attempting. 00:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:03:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:02 INFO - (blobuploader) - INFO - Done attempting. 00:03:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:03:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:03:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:03:03 INFO - (blobuploader) - INFO - Done attempting. 00:03:03 INFO - (blobuploader) - INFO - Iteration through files over. 00:03:03 INFO - Return code: 0 00:03:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:03:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:03:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/944562ca1ac600b723b53b8e592ca57651c1c363dafe1c0941ca58cdbf46f341dd5e759e227dbae7e1b1ea04d0ac1002ad1560434f382cbc263cca276a2022dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd8f5abf4f4b0affc50140dd06bf338ae61e2d75c14a51ae21db415d5ed89dd69b0bd94dfcc441d00936d79e3c6d1e1793803b14e11ca648a8e24b518956f350", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8feff58817ef0c64f743c7c3077740466ed20e3feec99b41eccf1dcb8f6fdce3a7cdb34aea5397cf2b03175a392b9682f0ba442a020a9e7617e164d9251a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/479bf16899998933b288e86fd3e08b2f16304644268c6b235feb86b823c4f2727394d2442622da615e42b6b31066b7637f9d74d368b3951ed9b47a1c12fd7e57"} 00:03:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:03:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:03:03 INFO - Contents: 00:03:03 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/944562ca1ac600b723b53b8e592ca57651c1c363dafe1c0941ca58cdbf46f341dd5e759e227dbae7e1b1ea04d0ac1002ad1560434f382cbc263cca276a2022dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd8f5abf4f4b0affc50140dd06bf338ae61e2d75c14a51ae21db415d5ed89dd69b0bd94dfcc441d00936d79e3c6d1e1793803b14e11ca648a8e24b518956f350", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8feff58817ef0c64f743c7c3077740466ed20e3feec99b41eccf1dcb8f6fdce3a7cdb34aea5397cf2b03175a392b9682f0ba442a020a9e7617e164d9251a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/479bf16899998933b288e86fd3e08b2f16304644268c6b235feb86b823c4f2727394d2442622da615e42b6b31066b7637f9d74d368b3951ed9b47a1c12fd7e57"} 00:03:03 INFO - Running post-run listener: copy_logs_to_upload_dir 00:03:03 INFO - Copying logs to upload dir... 00:03:03 INFO - mkdir: /builds/slave/test/build/upload/logs 00:03:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.440531 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-01-17 00:03:03.295677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 00:03:03.299317) ========= 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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/944562ca1ac600b723b53b8e592ca57651c1c363dafe1c0941ca58cdbf46f341dd5e759e227dbae7e1b1ea04d0ac1002ad1560434f382cbc263cca276a2022dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd8f5abf4f4b0affc50140dd06bf338ae61e2d75c14a51ae21db415d5ed89dd69b0bd94dfcc441d00936d79e3c6d1e1793803b14e11ca648a8e24b518956f350", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8feff58817ef0c64f743c7c3077740466ed20e3feec99b41eccf1dcb8f6fdce3a7cdb34aea5397cf2b03175a392b9682f0ba442a020a9e7617e164d9251a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/479bf16899998933b288e86fd3e08b2f16304644268c6b235feb86b823c4f2727394d2442622da615e42b6b31066b7637f9d74d368b3951ed9b47a1c12fd7e57"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011461 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1484631013/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/944562ca1ac600b723b53b8e592ca57651c1c363dafe1c0941ca58cdbf46f341dd5e759e227dbae7e1b1ea04d0ac1002ad1560434f382cbc263cca276a2022dc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dd8f5abf4f4b0affc50140dd06bf338ae61e2d75c14a51ae21db415d5ed89dd69b0bd94dfcc441d00936d79e3c6d1e1793803b14e11ca648a8e24b518956f350", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e8feff58817ef0c64f743c7c3077740466ed20e3feec99b41eccf1dcb8f6fdce3a7cdb34aea5397cf2b03175a392b9682f0ba442a020a9e7617e164d9251a0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/479bf16899998933b288e86fd3e08b2f16304644268c6b235feb86b823c4f2727394d2442622da615e42b6b31066b7637f9d74d368b3951ed9b47a1c12fd7e57"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 00:03:03.324158) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 00:03:03.324450) ========= 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=a0d2d2de893abaa8702a9954000001a7-1484638945.762428-556331384 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 00:03:03.408506) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 00:03:03.408795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 00:03:03.409070) ========= ========= Total master_lag: 0.17 =========