builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1483081101.15 results: success (0) buildid: 20161229194641 builduid: aa8406db44f64eca8963076f079a022a revision: 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.148175) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.148677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.149010) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.163893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.164228) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 22:58:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-12-29 22:58:21 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.310417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.491226) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.491583) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016775 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:21.541173) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 22:58:21.541556) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 --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 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 22:58:22,081 truncating revision to first 12 chars 2016-12-29 22:58:22,081 Setting DEBUG logging. 2016-12-29 22:58:22,081 attempt 1/10 2016-12-29 22:58:22,081 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/409bdaed6b9f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 22:58:23,332 unpacking tar archive at: mozilla-inbound-409bdaed6b9f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.532189 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 22:58:23.087854) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:23.088234) ========= script_repo_revision: 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:23.088671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:23.088973) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 22:58:23.099406) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-12-29 22:58:23.099714) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:58:23 INFO - MultiFileLogger online at 20161229 22:58:23 in /builds/slave/test 22:58:23 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 22:58:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:58:23 INFO - {'append_to_log': False, 22:58:23 INFO - 'base_work_dir': '/builds/slave/test', 22:58:23 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:58:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:58:23 INFO - 'branch': 'Mozilla-Inbound', 22:58:23 INFO - 'buildbot_json_path': 'buildprops.json', 22:58:23 INFO - 'config_files': ('talos/linux_config.py',), 22:58:23 INFO - 'default_actions': ('clobber', 22:58:23 INFO - 'read-buildbot-config', 22:58:23 INFO - 'download-and-extract', 22:58:23 INFO - 'populate-webroot', 22:58:23 INFO - 'create-virtualenv', 22:58:23 INFO - 'install', 22:58:23 INFO - 'run-tests'), 22:58:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:58:23 INFO - 'download_minidump_stackwalk': True, 22:58:23 INFO - 'download_symbols': 'ondemand', 22:58:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:58:23 INFO - 'tooltool.py': '/tools/tooltool.py', 22:58:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:58:23 INFO - '/tools/misc-python/virtualenv.py')}, 22:58:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:58:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:58:23 INFO - 'installer_path': 'installer.exe', 22:58:23 INFO - 'log_level': 'info', 22:58:23 INFO - 'log_name': 'talos', 22:58:23 INFO - 'log_to_console': True, 22:58:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:58:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:58:23 INFO - 'opt_config_files': (), 22:58:23 INFO - 'pip_index': False, 22:58:23 INFO - 'sps_profile': False, 22:58:23 INFO - 'sps_profile_interval': 0, 22:58:23 INFO - 'suite': 'dromaeojs', 22:58:23 INFO - 'system_bits': '32', 22:58:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:58:23 INFO - 'title': 'talos-linux64-ix-041', 22:58:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:58:23 INFO - 'use_talos_json': True, 22:58:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:58:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:58:23 INFO - 'work_dir': 'build'} 22:58:23 INFO - [mozharness: 2016-12-30 06:58:23.740350Z] Running clobber step. 22:58:23 INFO - Running pre-action listener: _resource_record_pre_action 22:58:23 INFO - Running main action method: clobber 22:58:23 INFO - rmtree: /builds/slave/test/build 22:58:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:58:24 INFO - Running post-action listener: _resource_record_post_action 22:58:24 INFO - [mozharness: 2016-12-30 06:58:24.229982Z] Finished clobber step (success) 22:58:24 INFO - [mozharness: 2016-12-30 06:58:24.230086Z] Running read-buildbot-config step. 22:58:24 INFO - Running pre-action listener: _resource_record_pre_action 22:58:24 INFO - Running main action method: read_buildbot_config 22:58:24 INFO - Using buildbot properties: 22:58:24 INFO - { 22:58:24 INFO - "project": "", 22:58:24 INFO - "product": "firefox", 22:58:24 INFO - "script_repo_revision": "production", 22:58:24 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:58:24 INFO - "repository": "", 22:58:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:58:24 INFO - "buildid": "20161229194641", 22:58:24 INFO - "stage_platform": "linux64-pgo", 22:58:24 INFO - "basedir": "/builds/slave/test", 22:58:24 INFO - "buildnumber": 2385, 22:58:24 INFO - "platform": "ubuntu64_hw", 22:58:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:58:24 INFO - "slavebuilddir": "test", 22:58:24 INFO - "branch": "mozilla-inbound", 22:58:24 INFO - "revision": "409bdaed6b9f41f001db03eb5a5469e5ad5685e5", 22:58:24 INFO - "slavename": "talos-linux64-ix-041", 22:58:24 INFO - "builduid": "aa8406db44f64eca8963076f079a022a", 22:58:24 INFO - "repo_path": "integration/mozilla-inbound" 22:58:24 INFO - } 22:58:24 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 22:58:24 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 22:58:24 INFO - Running post-action listener: _resource_record_post_action 22:58:24 INFO - [mozharness: 2016-12-30 06:58:24.232454Z] Finished read-buildbot-config step (success) 22:58:24 INFO - [mozharness: 2016-12-30 06:58:24.232543Z] Running download-and-extract step. 22:58:24 INFO - Running pre-action listener: _resource_record_pre_action 22:58:24 INFO - Running main action method: download_and_extract 22:58:24 INFO - mkdir: /builds/slave/test/build/tests 22:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:24 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:58:24 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/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 22:58:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/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 22:58:24 INFO - Downloaded 1361 bytes. 22:58:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:58:24 INFO - Using the following test package requirements: 22:58:24 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:58:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:58:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:58:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'jsshell-linux-x86_64.zip'], 22:58:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:58:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:58:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:58:24 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:58:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:58:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:24 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:58:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:58:24 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/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:58:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:58:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:58:24 INFO - Expected file size: 21834256 22:58:24 INFO - Obtained file size: 21834256 22:58:28 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 22:58:28 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/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:58:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:58:28 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:58:28 INFO - Expected file size: 21834256 22:58:28 INFO - Obtained file size: 21834256 22:58: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/1483062620/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 22:58: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/1483062620/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:58:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:58:33 INFO - Expected file size: 14409776 22:58:33 INFO - Obtained file size: 14409776 22:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:33 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:58:33 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/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 22:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/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 22:58:34 INFO - Downloaded 61368397 bytes. 22:58:34 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:58:34 INFO - mkdir: /builds/slave/test/properties 22:58:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:58:34 INFO - Writing to file /builds/slave/test/properties/build_url 22:58:34 INFO - Contents: 22:58:34 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:58:34 INFO - Running post-action listener: _resource_record_post_action 22:58:34 INFO - Running post-action listener: set_extra_try_arguments 22:58:34 INFO - [mozharness: 2016-12-30 06:58:34.660330Z] Finished download-and-extract step (success) 22:58:34 INFO - [mozharness: 2016-12-30 06:58:34.660559Z] Running populate-webroot step. 22:58:34 INFO - Running pre-action listener: _resource_record_pre_action 22:58:34 INFO - Running main action method: populate_webroot 22:58:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:58:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:58:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:58:34 INFO - '--tppagecycles', 22:58:34 INFO - '7'], 22:58:34 INFO - 'tests': ['tsvgm']}}, 22:58:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:58:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:58:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:58:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:58:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['damp', 'tps']}, 22:58:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'tests': ['damp', 'tps']}, 22:58:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['dromaeo_dom']}, 22:58:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:58:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['basic_compositor_video']}, 22:58:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:58:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tsvgx', 22:58:34 INFO - 'tsvgr_opacity', 22:58:34 INFO - 'tart', 22:58:34 INFO - 'tscrollx', 22:58:34 INFO - 'cart', 22:58:34 INFO - 'tsvg_static']}, 22:58:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:58:34 INFO - 'tsvgr_opacity', 22:58:34 INFO - 'tart', 22:58:34 INFO - 'tscrollx', 22:58:34 INFO - 'cart', 22:58:34 INFO - 'tsvg_static']}, 22:58:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tp5o']}, 22:58:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:58:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s', 22:58:34 INFO - '--xperf_path', 22:58:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:34 INFO - 'tests': ['tp5n']}, 22:58:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:58:34 INFO - 'talos_options': ['--xperf_path', 22:58:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:34 INFO - 'tests': ['tp5n']}}, 22:58:34 INFO - 'talos.zip': {'path': '', 22:58:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:58:34 INFO - Running post-action listener: _resource_record_post_action 22:58:34 INFO - [mozharness: 2016-12-30 06:58:34.679043Z] Finished populate-webroot step (success) 22:58:34 INFO - [mozharness: 2016-12-30 06:58:34.679133Z] Running create-virtualenv step. 22:58:34 INFO - Running pre-action listener: _resource_record_pre_action 22:58:34 INFO - Running main action method: create_virtualenv 22:58:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:58:34 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 22:58:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:58:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:58:34 INFO - Using real prefix '/usr' 22:58:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:58:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:58:37 INFO - Installing pip.................done. 22:58:37 INFO - Return code: 0 22:58:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:58:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:58:37 INFO - Reading from file tmpfile_stdout 22:58:37 INFO - Output received: 22:58:37 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) 22:58:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:58:37 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')]} 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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')]} 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:37 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 22:58:37 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 22:58:37 INFO - Using env: {'DISPLAY': ':0', 22:58:37 INFO - 'HOME': '/home/cltbld', 22:58:37 INFO - 'LANG': 'en_US.UTF-8', 22:58:37 INFO - 'LANGUAGE': 'en_US:en', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MAIL': '/var/mail/cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SHLVL': '1', 22:58:37 INFO - 'TERM': 'linux', 22:58:37 INFO - 'TMOUT': '86400', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - '_': '/tools/buildbot/bin/python'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 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 22:58:37 INFO - Cleaning up... 22:58:37 INFO - Return code: 0 22:58:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:58:37 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')]} 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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')]} 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:37 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 22:58:37 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 22:58:37 INFO - Using env: {'DISPLAY': ':0', 22:58:37 INFO - 'HOME': '/home/cltbld', 22:58:37 INFO - 'LANG': 'en_US.UTF-8', 22:58:37 INFO - 'LANGUAGE': 'en_US:en', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MAIL': '/var/mail/cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SHLVL': '1', 22:58:37 INFO - 'TERM': 'linux', 22:58:37 INFO - 'TMOUT': '86400', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - '_': '/tools/buildbot/bin/python'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 INFO - Downloading/unpacking psutil>=3.1.1 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:58:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:40 INFO - Installing collected packages: psutil 22:58:40 INFO - Running setup.py install for psutil 22:58:40 INFO - building 'psutil._psutil_linux' extension 22:58:40 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 22:58:40 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 22:58:40 INFO - building 'psutil._psutil_posix' extension 22:58:40 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 22:58:40 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 22:58:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:40 INFO - Successfully installed psutil 22:58:40 INFO - Cleaning up... 22:58:40 INFO - Return code: 0 22:58:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:40 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:40 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 22:58:40 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 22:58:40 INFO - Using env: {'DISPLAY': ':0', 22:58:40 INFO - 'HOME': '/home/cltbld', 22:58:40 INFO - 'LANG': 'en_US.UTF-8', 22:58:40 INFO - 'LANGUAGE': 'en_US:en', 22:58:40 INFO - 'LOGNAME': 'cltbld', 22:58:40 INFO - 'MAIL': '/var/mail/cltbld', 22:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:40 INFO - 'MOZ_NO_REMOTE': '1', 22:58:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:40 INFO - 'NO_EM_RESTART': '1', 22:58:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:40 INFO - 'PWD': '/builds/slave/test', 22:58:40 INFO - 'SHELL': '/bin/bash', 22:58:40 INFO - 'SHLVL': '1', 22:58:40 INFO - 'TERM': 'linux', 22:58:40 INFO - 'TMOUT': '86400', 22:58:40 INFO - 'USER': 'cltbld', 22:58:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:40 INFO - '_': '/tools/buildbot/bin/python'} 22:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:58:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:58:42 INFO - Installing collected packages: mozsystemmonitor 22:58:42 INFO - Running setup.py install for mozsystemmonitor 22:58:42 INFO - Successfully installed mozsystemmonitor 22:58:42 INFO - Cleaning up... 22:58:43 INFO - Return code: 0 22:58:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:43 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:43 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 22:58:43 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 22:58:43 INFO - Using env: {'DISPLAY': ':0', 22:58:43 INFO - 'HOME': '/home/cltbld', 22:58:43 INFO - 'LANG': 'en_US.UTF-8', 22:58:43 INFO - 'LANGUAGE': 'en_US:en', 22:58:43 INFO - 'LOGNAME': 'cltbld', 22:58:43 INFO - 'MAIL': '/var/mail/cltbld', 22:58:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:43 INFO - 'MOZ_NO_REMOTE': '1', 22:58:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:43 INFO - 'NO_EM_RESTART': '1', 22:58:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:43 INFO - 'PWD': '/builds/slave/test', 22:58:43 INFO - 'SHELL': '/bin/bash', 22:58:43 INFO - 'SHLVL': '1', 22:58:43 INFO - 'TERM': 'linux', 22:58:43 INFO - 'TMOUT': '86400', 22:58:43 INFO - 'USER': 'cltbld', 22:58:43 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:43 INFO - '_': '/tools/buildbot/bin/python'} 22:58:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:43 INFO - Downloading/unpacking jsonschema==2.5.1 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:45 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:45 INFO - warning: no files found matching '*.txt' 22:58:45 INFO - no previously-included directories found matching 'build' 22:58:45 INFO - no previously-included directories found matching 'dist' 22:58:45 INFO - no previously-included directories found matching '.git*' 22:58:45 INFO - Installing collected packages: jsonschema, functools32 22:58:45 INFO - Running setup.py install for functools32 22:58:45 INFO - warning: no files found matching '*.txt' 22:58:45 INFO - no previously-included directories found matching 'build' 22:58:45 INFO - no previously-included directories found matching 'dist' 22:58:45 INFO - no previously-included directories found matching '.git*' 22:58:45 INFO - Successfully installed jsonschema functools32 22:58:45 INFO - Cleaning up... 22:58:45 INFO - Return code: 0 22:58:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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', '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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:58: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 functools32==3.2.3-2 22:58:45 INFO - Using env: {'DISPLAY': ':0', 22:58:45 INFO - 'HOME': '/home/cltbld', 22:58:45 INFO - 'LANG': 'en_US.UTF-8', 22:58:45 INFO - 'LANGUAGE': 'en_US:en', 22:58:45 INFO - 'LOGNAME': 'cltbld', 22:58:45 INFO - 'MAIL': '/var/mail/cltbld', 22:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:45 INFO - 'MOZ_NO_REMOTE': '1', 22:58:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:45 INFO - 'NO_EM_RESTART': '1', 22:58:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:45 INFO - 'PWD': '/builds/slave/test', 22:58:45 INFO - 'SHELL': '/bin/bash', 22:58:45 INFO - 'SHLVL': '1', 22:58:45 INFO - 'TERM': 'linux', 22:58:45 INFO - 'TMOUT': '86400', 22:58:45 INFO - 'USER': 'cltbld', 22:58:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:45 INFO - '_': '/tools/buildbot/bin/python'} 22:58:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:58:45 INFO - Cleaning up... 22:58:45 INFO - Return code: 0 22:58:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:46 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:46 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 22:58:46 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 22:58:46 INFO - Using env: {'DISPLAY': ':0', 22:58:46 INFO - 'HOME': '/home/cltbld', 22:58:46 INFO - 'LANG': 'en_US.UTF-8', 22:58:46 INFO - 'LANGUAGE': 'en_US:en', 22:58:46 INFO - 'LOGNAME': 'cltbld', 22:58:46 INFO - 'MAIL': '/var/mail/cltbld', 22:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:46 INFO - 'MOZ_NO_REMOTE': '1', 22:58:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:46 INFO - 'NO_EM_RESTART': '1', 22:58:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:46 INFO - 'PWD': '/builds/slave/test', 22:58:46 INFO - 'SHELL': '/bin/bash', 22:58:46 INFO - 'SHLVL': '1', 22:58:46 INFO - 'TERM': 'linux', 22:58:46 INFO - 'TMOUT': '86400', 22:58:46 INFO - 'USER': 'cltbld', 22:58:46 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:46 INFO - '_': '/tools/buildbot/bin/python'} 22:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:58:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:58:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:48 INFO - Downloading docopt-0.6.1.tar.gz 22:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:58:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:58:49 INFO - Running setup.py install for blobuploader 22:58:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:58:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:58:49 INFO - Running setup.py install for requests 22:58:49 INFO - Running setup.py install for docopt 22:58:49 INFO - Successfully installed blobuploader requests docopt 22:58:49 INFO - Cleaning up... 22:58:49 INFO - Return code: 0 22:58:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:49 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:49 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 22:58:49 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 22:58:49 INFO - Using env: {'DISPLAY': ':0', 22:58:49 INFO - 'HOME': '/home/cltbld', 22:58:49 INFO - 'LANG': 'en_US.UTF-8', 22:58:49 INFO - 'LANGUAGE': 'en_US:en', 22:58:49 INFO - 'LOGNAME': 'cltbld', 22:58:49 INFO - 'MAIL': '/var/mail/cltbld', 22:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:49 INFO - 'MOZ_NO_REMOTE': '1', 22:58:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:49 INFO - 'NO_EM_RESTART': '1', 22:58:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:49 INFO - 'PWD': '/builds/slave/test', 22:58:49 INFO - 'SHELL': '/bin/bash', 22:58:49 INFO - 'SHLVL': '1', 22:58:49 INFO - 'TERM': 'linux', 22:58:49 INFO - 'TMOUT': '86400', 22:58:49 INFO - 'USER': 'cltbld', 22:58:49 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:49 INFO - '_': '/tools/buildbot/bin/python'} 22:58:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:49 INFO - Running setup.py (path:/tmp/pip-AW9FIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:49 INFO - Running setup.py (path:/tmp/pip-WVCTO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:49 INFO - Running setup.py (path:/tmp/pip-QX0Av2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:50 INFO - Running setup.py (path:/tmp/pip-ZSBC3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:50 INFO - Running setup.py (path:/tmp/pip-OysBlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:50 INFO - Running setup.py (path:/tmp/pip-MbMZPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:50 INFO - Running setup.py (path:/tmp/pip-oXNF_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:50 INFO - Running setup.py (path:/tmp/pip-HfiVfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:50 INFO - Running setup.py (path:/tmp/pip-OQLIxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:50 INFO - Running setup.py (path:/tmp/pip-gQSvHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:50 INFO - Running setup.py (path:/tmp/pip-k9bXZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:50 INFO - Running setup.py (path:/tmp/pip-xwTy5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:50 INFO - Running setup.py (path:/tmp/pip-CDfJqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:50 INFO - Running setup.py (path:/tmp/pip-d7kZXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:50 INFO - Running setup.py (path:/tmp/pip-icQX_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:50 INFO - Running setup.py (path:/tmp/pip-WpL5ED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:51 INFO - Running setup.py (path:/tmp/pip-1mvpgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:58:51 INFO - Running setup.py install for manifestparser 22:58:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozcrash 22:58:51 INFO - Running setup.py install for mozdebug 22:58:51 INFO - Running setup.py install for mozdevice 22:58:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozfile 22:58:51 INFO - Running setup.py install for mozhttpd 22:58:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozinfo 22:58:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozInstall 22:58:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozleak 22:58:51 INFO - Running setup.py install for mozlog 22:58:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Running setup.py install for moznetwork 22:58:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Running setup.py install for mozprocess 22:58:52 INFO - Running setup.py install for mozprofile 22:58:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Running setup.py install for mozrunner 22:58:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Running setup.py install for mozscreenshot 22:58:52 INFO - Running setup.py install for moztest 22:58:52 INFO - Running setup.py install for mozversion 22:58:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:58:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:58:52 INFO - Cleaning up... 22:58:52 INFO - Return code: 0 22:58:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:52 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')]} 22:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:52 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')]} 22:58:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:52 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:52 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 22:58:52 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 22:58:52 INFO - Using env: {'DISPLAY': ':0', 22:58:52 INFO - 'HOME': '/home/cltbld', 22:58:52 INFO - 'LANG': 'en_US.UTF-8', 22:58:52 INFO - 'LANGUAGE': 'en_US:en', 22:58:52 INFO - 'LOGNAME': 'cltbld', 22:58:52 INFO - 'MAIL': '/var/mail/cltbld', 22:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:52 INFO - 'MOZ_NO_REMOTE': '1', 22:58:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:52 INFO - 'NO_EM_RESTART': '1', 22:58:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:52 INFO - 'PWD': '/builds/slave/test', 22:58:52 INFO - 'SHELL': '/bin/bash', 22:58:52 INFO - 'SHLVL': '1', 22:58:52 INFO - 'TERM': 'linux', 22:58:52 INFO - 'TMOUT': '86400', 22:58:52 INFO - 'USER': 'cltbld', 22:58:52 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:52 INFO - '_': '/tools/buildbot/bin/python'} 22:58:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:53 INFO - Running setup.py (path:/tmp/pip-RaIYAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:53 INFO - Running setup.py (path:/tmp/pip-1JZaVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:53 INFO - Running setup.py (path:/tmp/pip-3Mp1xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:53 INFO - Running setup.py (path:/tmp/pip-kSayrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:53 INFO - Running setup.py (path:/tmp/pip-37kHkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:53 INFO - Running setup.py (path:/tmp/pip-O_a_Tz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:53 INFO - Running setup.py (path:/tmp/pip-5OpFQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:53 INFO - Running setup.py (path:/tmp/pip-f5_91i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:53 INFO - Running setup.py (path:/tmp/pip-JAFnqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:53 INFO - Running setup.py (path:/tmp/pip-qA6sOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:53 INFO - Running setup.py (path:/tmp/pip-8BjTkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:53 INFO - Running setup.py (path:/tmp/pip-7tEXwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:54 INFO - Running setup.py (path:/tmp/pip-prEzzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:54 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)) 22:58:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:54 INFO - Running setup.py (path:/tmp/pip-QfO34o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:54 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)) 22:58:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:54 INFO - Running setup.py (path:/tmp/pip-K3G3p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:54 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)) 22:58:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:54 INFO - Running setup.py (path:/tmp/pip-e2vt4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:58:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:54 INFO - Running setup.py (path:/tmp/pip-BXVrlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:54 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)) 22:58:54 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)) 22:58:54 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)) 22:58:54 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)) 22:58:54 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)) 22:58:54 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)) 22:58:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:58:54 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 22:58:54 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 22:58:54 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 22:58:54 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 22:58:54 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 22:58:54 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 22:58:56 INFO - Downloading blessings-1.6.tar.gz 22:58:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:56 INFO - Installing collected packages: blessings 22:58:56 INFO - Running setup.py install for blessings 22:58:56 INFO - Successfully installed blessings 22:58:56 INFO - Cleaning up... 22:58:56 INFO - Return code: 0 22:58:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:58:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:56 INFO - Reading from file tmpfile_stdout 22:58:56 INFO - Current package versions: 22:58:56 INFO - argparse == 1.2.1 22:58:56 INFO - blessings == 1.6 22:58:56 INFO - blobuploader == 1.2.4 22:58:56 INFO - docopt == 0.6.1 22:58:56 INFO - functools32 == 3.2.3-2 22:58:56 INFO - jsonschema == 2.5.1 22:58:56 INFO - manifestparser == 1.1 22:58:56 INFO - mozInstall == 1.12 22:58:56 INFO - mozcrash == 1.0 22:58:56 INFO - mozdebug == 0.1 22:58:56 INFO - mozdevice == 0.49 22:58:56 INFO - mozfile == 1.2 22:58:56 INFO - mozhttpd == 0.7 22:58:56 INFO - mozinfo == 0.9 22:58:56 INFO - mozleak == 0.1 22:58:56 INFO - mozlog == 3.4 22:58:56 INFO - moznetwork == 0.27 22:58:56 INFO - mozprocess == 0.23 22:58:56 INFO - mozprofile == 0.28 22:58:56 INFO - mozrunner == 6.13 22:58:56 INFO - mozscreenshot == 0.1 22:58:56 INFO - mozsystemmonitor == 0.3 22:58:56 INFO - moztest == 0.7 22:58:56 INFO - mozversion == 1.4 22:58:56 INFO - psutil == 3.1.1 22:58:56 INFO - requests == 1.2.3 22:58:56 INFO - wsgiref == 0.1.2 22:58:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:56 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:56 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 22:58:56 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 22:58:56 INFO - Using env: {'DISPLAY': ':0', 22:58:56 INFO - 'HOME': '/home/cltbld', 22:58:56 INFO - 'LANG': 'en_US.UTF-8', 22:58:56 INFO - 'LANGUAGE': 'en_US:en', 22:58:56 INFO - 'LOGNAME': 'cltbld', 22:58:56 INFO - 'MAIL': '/var/mail/cltbld', 22:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:56 INFO - 'MOZ_NO_REMOTE': '1', 22:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:56 INFO - 'NO_EM_RESTART': '1', 22:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:56 INFO - 'PWD': '/builds/slave/test', 22:58:56 INFO - 'SHELL': '/bin/bash', 22:58:56 INFO - 'SHLVL': '1', 22:58:56 INFO - 'TERM': 'linux', 22:58:56 INFO - 'TMOUT': '86400', 22:58:56 INFO - 'USER': 'cltbld', 22:58:56 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:56 INFO - '_': '/tools/buildbot/bin/python'} 22:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 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)) 22:58:57 INFO - Cleaning up... 22:58:57 INFO - Return code: 0 22:58:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:57 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 0x227a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x238aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25ec630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', '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 22:58:57 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 22:58:57 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 22:58:57 INFO - Using env: {'DISPLAY': ':0', 22:58:57 INFO - 'HOME': '/home/cltbld', 22:58:57 INFO - 'LANG': 'en_US.UTF-8', 22:58:57 INFO - 'LANGUAGE': 'en_US:en', 22:58:57 INFO - 'LOGNAME': 'cltbld', 22:58:57 INFO - 'MAIL': '/var/mail/cltbld', 22:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:57 INFO - 'MOZ_NO_REMOTE': '1', 22:58:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:57 INFO - 'NO_EM_RESTART': '1', 22:58:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:57 INFO - 'PWD': '/builds/slave/test', 22:58:57 INFO - 'SHELL': '/bin/bash', 22:58:57 INFO - 'SHLVL': '1', 22:58:57 INFO - 'TERM': 'linux', 22:58:57 INFO - 'TMOUT': '86400', 22:58:57 INFO - 'USER': 'cltbld', 22:58:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:57 INFO - '_': '/tools/buildbot/bin/python'} 22:58:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:58:57 INFO - Cleaning up... 22:58:57 INFO - Return code: 0 22:58:57 INFO - Running post-action listener: _resource_record_post_action 22:58:57 INFO - Running post-action listener: _start_resource_monitoring 22:58:57 INFO - Starting resource monitoring. 22:58:57 INFO - [mozharness: 2016-12-30 06:58:57.341592Z] Finished create-virtualenv step (success) 22:58:57 INFO - [mozharness: 2016-12-30 06:58:57.342116Z] Running install step. 22:58:57 INFO - Running pre-action listener: _resource_record_pre_action 22:58:57 INFO - Running main action method: install 22:58:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:57 INFO - Reading from file tmpfile_stdout 22:58:57 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:58:57 INFO - Reading from file tmpfile_stdout 22:58:57 INFO - Output received: 22:58:57 INFO - Usage: mozinstall [options] installer 22:58:57 INFO - Options: 22:58:57 INFO - -h, --help show this help message and exit 22:58:57 INFO - -d DEST, --destination=DEST 22:58:57 INFO - Directory to install application into. [default: 22:58:57 INFO - "/builds/slave/test"] 22:58:57 INFO - --app=APP Application being installed. [default: firefox] 22:58:57 INFO - mkdir: /builds/slave/test/build/application 22:58:57 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'] 22:58:57 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 22:59:14 INFO - Reading from file tmpfile_stdout 22:59:14 INFO - Output received: 22:59:14 INFO - /builds/slave/test/build/application/firefox/firefox 22:59:14 INFO - Running post-action listener: _resource_record_post_action 22:59:14 INFO - [mozharness: 2016-12-30 06:59:14.981522Z] Finished install step (success) 22:59:14 INFO - [mozharness: 2016-12-30 06:59:14.981800Z] Running run-tests step. 22:59:14 INFO - Running pre-action listener: _resource_record_pre_action 22:59:14 INFO - Running main action method: run_tests 22:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:59:14 INFO - Python 2.7.3 22:59:14 INFO - Return code: 0 22:59:14 INFO - grabbing minidump binary from tooltool 22:59:14 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')]} 22:59:14 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 0x2675850>, '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 0x25ea6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25eab60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:59:14 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 22:59:14 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 22:59:14 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 22:59:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:59:15 INFO - Return code: 0 22:59:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:59:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:59:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:59:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:59:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:59:15 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/1483062620/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 22:59:15 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/1483062620/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 22:59:15 INFO - Using env: {'DISPLAY': ':0', 22:59:15 INFO - 'HOME': '/home/cltbld', 22:59:15 INFO - 'LANG': 'en_US.UTF-8', 22:59:15 INFO - 'LANGUAGE': 'en_US:en', 22:59:15 INFO - 'LOGNAME': 'cltbld', 22:59:15 INFO - 'MAIL': '/var/mail/cltbld', 22:59:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:59:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:15 INFO - 'MOZ_NO_REMOTE': '1', 22:59:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:59:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:15 INFO - 'NO_EM_RESTART': '1', 22:59:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:15 INFO - 'PWD': '/builds/slave/test', 22:59:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:59:15 INFO - 'SHELL': '/bin/bash', 22:59:15 INFO - 'SHLVL': '1', 22:59:15 INFO - 'TERM': 'linux', 22:59:15 INFO - 'TMOUT': '86400', 22:59:15 INFO - 'USER': 'cltbld', 22:59:15 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555', 22:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:15 INFO - '_': '/tools/buildbot/bin/python'} 22:59:15 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/1483062620/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 22:59:15 INFO - mozversion application_buildid: 20161229175020 22:59:15 INFO - mozversion application_changeset: 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 22:59:15 INFO - mozversion application_display_name: Nightly 22:59:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:59:15 INFO - mozversion application_name: Firefox 22:59:15 INFO - mozversion application_remotingname: firefox 22:59:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:59:15 INFO - mozversion application_vendor: Mozilla 22:59:15 INFO - mozversion application_version: 53.0a1 22:59:15 INFO - mozversion platform_buildid: 20161229175020 22:59:15 INFO - mozversion platform_changeset: 409bdaed6b9f41f001db03eb5a5469e5ad5685e5 22:59:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:59:15 INFO - mozversion platform_version: 53.0a1 22:59:15 INFO - using testdate: 1483081155 22:59:15 INFO - actual date: 1483081155 22:59:15 INFO - starting webserver on 'localhost:42447' 22:59:15 INFO - SUITE-START | Running 2 tests 22:59:15 INFO - TEST-START | dromaeo_css 22:59:15 INFO - operating with platform_type : linux_ 22:59:15 INFO - Initialising browser for dromaeo_css test... 22:59:15 INFO - TEST-INFO | started process 13011 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpteNu2f/profile http://localhost:42447/getInfo.html) 22:59:15 INFO - PROCESS | 13011 | 1483081155776 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 22:59:19 INFO - PROCESS | 13011 | __metrics Screen width/height:1600/1200 22:59:19 INFO - PROCESS | 13011 | colorDepth:24 22:59:19 INFO - PROCESS | 13011 | Browser inner width/height: 1024/697 22:59:19 INFO - PROCESS | 13011 | __metrics 22:59:19 INFO - PROCESS | 13011 | 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" 22:59:19 INFO - PROCESS | 13011 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:59:20 INFO - PROCESS | 13011 | console.error: PushService: 22:59:20 INFO - PROCESS | 13011 | stateChangeProcessEnqueue: Error transitioning state 22:59:20 INFO - PROCESS | 13011 | UnknownError 22:59:21 INFO - TEST-INFO | 13011: exit 0 22:59:21 INFO - Browser initialized. 22:59:21 INFO - Running cycle 1/1 for dromaeo_css test... 22:59:21 INFO - TEST-INFO | started process 13092 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpteNu2f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:32 INFO - PROCESS | 13092 | RSS: Main: 146731008 22:59:32 INFO - PROCESS | 13092 | 23:02:34 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42447/tests/dromaeo/cssquery-ext.html) 23:02:35 INFO - PROCESS | 13092 | RSS: Main: 248012800 23:02:35 INFO - PROCESS | 13092 | 23:04:22 INFO - PROCESS | 13092 | ************************* 23:04:22 INFO - PROCESS | 13092 | A coding exception was thrown and uncaught in a Task. 23:04:22 INFO - PROCESS | 13092 | 23:04:22 INFO - PROCESS | 13092 | Full message: ReferenceError: fetch is not defined 23:04:22 INFO - PROCESS | 13092 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 23:04:22 INFO - PROCESS | 13092 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 23:04:22 INFO - PROCESS | 13092 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 23:04:22 INFO - PROCESS | 13092 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:04:22 INFO - PROCESS | 13092 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:04:22 INFO - PROCESS | 13092 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:04:22 INFO - PROCESS | 13092 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 23:04:22 INFO - PROCESS | 13092 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:04:22 INFO - PROCESS | 13092 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:04:22 INFO - PROCESS | 13092 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:04:22 INFO - PROCESS | 13092 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 23:04:22 INFO - PROCESS | 13092 | 23:04:22 INFO - PROCESS | 13092 | ************************* 23:05:38 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-ext.html (next: http://localhost:42447/tests/dromaeo/cssquery-jquery.html) 23:05:38 INFO - PROCESS | 13092 | RSS: Main: 225959936 23:05:38 INFO - PROCESS | 13092 | 23:08:40 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42447/tests/dromaeo/cssquery-mootools.html) 23:08:41 INFO - PROCESS | 13092 | RSS: Main: 218935296 23:08:41 INFO - PROCESS | 13092 | 23:11:43 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42447/tests/dromaeo/cssquery-prototype.html) 23:11:43 INFO - PROCESS | 13092 | RSS: Main: 204009472 23:11:43 INFO - PROCESS | 13092 | 23:14:46 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42447/tests/dromaeo/cssquery-yui.html) 23:14:47 INFO - PROCESS | 13092 | RSS: Main: 180875264 23:14:47 INFO - PROCESS | 13092 | 23:17:49 INFO - PROCESS | 13092 | Cycle 1(1): loaded http://localhost:42447/tests/dromaeo/cssquery-yui.html (next: http://localhost:42447/tests/dromaeo/cssquery-dojo.html) 23:17:50 INFO - PROCESS | 13092 | RSS: Main: 276652032 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | __start_tp_report 23:17:50 INFO - PROCESS | 13092 | _x_x_mozilla_page_load 23:17:50 INFO - PROCESS | 13092 | _x_x_mozilla_page_load_details 23:17:50 INFO - PROCESS | 13092 | |i|pagename|runs| 23:17:50 INFO - PROCESS | 13092 | |0;dojo.html;3139.1367373972153;3163.825989570573;3173.6350319713233;3199;3218.147190994387;5251.763670634821;5298.549623282021;5338.3860856001575;5383.892322153554;5399.298091248133;5333.573314134869;5339.786408543654;5346.438623944478;5402.243685883969;5451.04606693828;4814.879628009301;4840.177169881129;4880.755962201893;4890.633202509826;4922.384701912261;4974.452810190876;4982.402111746577;4987.202047672373;4989.077020751154;4996.875078123039;4762.261849413347;4778.187708089633;4808.543188397097;4942.802287908497;4966.900661986776;4872.3422337984275;4880.048390563841;4881.731504767236;4914.827981020683;4935.136351138536;4825.5174482551465;4857.417109946794;4888.046830867982;4899.510048995107;4927.679700819471;5264.315638966948;5274.182501712241;5398.433164517708;5400.108982017962;5404.839425411718;5707.543396528309;5766.7981620643495;5767.423257674241;5810.970945145247;5866.823995280149;5834.5624078194305;5835;5836.737346819404;5846.795362162304;5851.531877449836;5229.058769421541;5414.6209765316835;5419.078756611346;5427.3215848019;5427.511523962823;5823.679697616672;5835.35811060783;5847.8245652630485;5862.14998825168;5987.072003839412;5624.943750562524;5675.943240567624;5683.2895888014;5684.54523638108;5690.715464226855;5743.454371834752;5753.367129615739;5780.855478612984;5792.957267691856;5806.535477161817;5626.099824028136;5645.943540564624;5661.235733175969;5757.366689664133;5783.421657834183;5702.714864256771;5703.486686198262;5705.1727499512335;5706.3722990471015;5706.629069110578;4344.6089851913475;4347;4349.011926309811;4352.216601011848;4382.715123516961;3839.865604703822;3841.9039524012123;3855.63371479709;3858.401947698055;3879.3599056155417;5472.398036216013;5472.91790623141;5473.534749546254;5476.479734425223;5512.39363669996;5731.168980497888;5764.7117644117625;5769.624974376652;5782.190493330937;5782.45066718661;5343.786248550092;5376.354837419534;5426.457354264542;5453.209284653703;5462.180672899097;4837.153498137882;4838.371011768448;4840.322354870321;4861.708297502161;4882.12121818069;6046.27444706638;6049.51603871698;6054.182685337511;6056.909146362808;6060.030395136757;5481.287432633732;5513.393526712139;5713.8285851424525;5803.419658034163;5866.442687944638;6006.189164462741;6078.361772014052;6079.848003799941;6133.355997620186;6137.048757442603;5692.7722891083895;5732.05421105513;5733.111367738007;5743.396943321059;5746.195532625519;5683.687397193194;5734.053881109737;5773.607784282322;5826.300843898759;5838.211841401357;5552.278203833475;5573.665580065209;5593.972030139823;5597.216389705363;5641.717914104361;5591.552675786009;5598.944010560005;5632.098864181711;5652.745626446737;5662.915056274242;5496.093144631251;5503.3395992481155;5552.666839989613;5640.661560306231;5648.209250704823;5670.914936276042;5675.77296908119;5728.713564321684;5758.884822303614;5796.62321949072;5590.077637189818;5620.1288800236025;5636.549076073986;5639.577031722719;5640.71796410186 23:17:50 INFO - PROCESS | 13092 | |1;ext.html;549.2859282932188;583.2767368463107;587.4888846703369;592.6532978207748;593.1636392686312;32437;41165.38251926223;42246.366304505456;42387.78806105973;45497.54502454983;43883.78058109705;44141.7792911035;44183.33724994128;44192.3371149433;44256.33615495762;8837.646494140226;9023.548822558878;9025.18773310402;9032;9032;8687.087855775148;8955.776105597379;10536.578536858517;10794.946025269863;10816.351018938849;10742.624008159715;10768.192385571074;10808.297460665071;10816.567337306498;10819.729506762355;10760.784784304271;10777.245592808507;10818.621348252773;10831.729206769814;10845.295055821385;10581.418022008864;10654.4140072296;10660.093892019187;10662.040416362486;10662.573497060108;40955.795221024004;41051.589484105076;41157.58842411597;41207.38188927169;41273;3974.682025437958;4057.7159598828093;4109.137081212949;4114.280000999843;4117.155983023473;30458.390832183453;30558.263469162317;30755.846220768977;30759.538606920913;31372.686273137206;4104.91790164195;4321.9135617287475;4325.718828276184;4326.8269269229195;4373.409589705381;321093;321627;321748.3912580446;322012;322275;50175;50176;50302.74848625733;50345.49654503481;50366;57396.834284914556;57512.424875751545;57515.71242143762;57571.42428575744;57609.711951439975;15919.283632236577;16334.183290835648;16382.918085409736;16390.918045409937;16391.180440977903;11428.971392574707;11699.23954942926;12556.18384805003;12619.810702839464;12620.053495987837;15509.844901551065;15770.605734856721;16092.620549684698;16282.918585407235;16367.754483682755;6584.637844918576;6626.171728533934;6658.201015878126;6727.865442691119;6731.932680673131;912.4479689787582;919.9264058875274;922.6125027488471;924.8843894513186;932.7388331267256;29253;31140.532892006184;31245.843770781;31409.528857067162;31707.84146079301;1058.3914249306526;1062.4475272858072;1067.0823092140752;1103.3545375955157;1194.9761004779855;15894.284757185955;16329.91835040841;16506.83493165053;16569.25438355277;16671.83328166727;482589;484664;486197;486923;487970;49894.75052624714;49970.75014624903;49998.50001499939;50031.499685003415;50078.49921500811;1369.486442584031;1404.1925892611584;1416.5113036002356;1423.665438621923;1433.4624515806572;28680.713192867803;29703;29858.253543661584;30613.693863061086;30710.539341909443;46032.769836150605;48240.276395854795;48760.51239487559;48803.26795098148;53158.73420632872;416796;420833.0625121816;420865;421223;422064;23016.194433194756;23618.17336393218;23980.880095599412;25618.871905640353;25784.226473205836;35355.82322088373;38545.807270963465;38819.2236155281;38939.80530097445;39367;33546.66453335436;33663;33944.15139621431;34099.829500852335;34143;33372.833135835135;33545.83227083849;33704.66295337113;33802.28174900808;33832.66167338295;33229;33942.83028584841;33956;34229.315413692086;34552.82723586366;28289.858550707937;30518.38963220679;30757.538636920017;30807.383852323277;30853.92011279684;23096.884515577316;23307.883460582587;23538.70537120475;25339.49321013606;26640.866795665897 23:17:50 INFO - PROCESS | 13092 | |2;jquery.html;378.6705566157443;382.3061144023597;396.0929471510242;396.7500474700938;409.89137878462213;14516.709665806677;14912.850871491282;16945.83054169458;18950.77836767751;19102;15652.608684782886;17957.7306340405;18299.725504117414;18583.070846457656;18661.813381866177;5425.457454254582;5466.371367292772;5664.688442135681;5667.801626943058;5671.2060311556415;5495.53287970523;5546.77812887484;5568.136938774496;5584.106542953128;5597.692126933017;4247.936280955788;4491.955080449203;5560.499555040045;5903.380145084769;6159.260888693363;6031.125486804389;6169.352218017133;6248.9062664060075;6261.561690681631;6264.843378915518;5441.374241962186;5478.561715062785;5484.643498172606;5487.341519017744;5487.341519017744;21416.46458838526;21500.46248843776;21884.781152188432;21902.890485547472;21953.560928781335;2807.845568493732;2870.282429392652;2871.296532349567;2872.8707208175683;2902.288939209886;13760.724785504333;13972.231527265996;14073.36669849849;14179.078359906676;14320.713585728332;1922.6731455652432;1969.4156634924443;1980.4157773456866;1997.4806550297028;2014.6977953306975;76102.92095962264;76670.23329766687;77121.61439192768;77173.61413192897;77886.61056694681;10232.623234332652;10314.329568578018;10485.318454300446;10523.894761052292;10540.209484288709;14587.781183282259;14590.85409145895;14596;14602.415903363959;14611.634709132357;7620.504667196615;7837.207335817058;7915.089764677021;8041.155678653658;8124.796880078045;7567.508111972704;7662.693492260359;7667.194944530744;7840.411969102226;7855.8821617675785;6452.935470645328;6471.838204044842;6482.157319548429;6529.183852018497;6664.866702666017;1753.7632419623442;1769.637224369027;1770.194561474526;1790.1228398084845;1824.744535764994;449.19593926871187;449.2407830765995;458.1547045700677;864.386285737121;876.509154873273;11094.778104437866;11361.0343120834;11562.710932226762;11777.411129443493;11833.763324733629;488.7043338780058;490.0908814149772;498.56624736479495;878.9560521974004;929.6374413978554;9558.761030974281;9574.47340396285;9872.308938374388;9974;10897.128229741602;418332.72500912513;420040;425658;434327;434401;64619.35380646228;78812;82327.58836205781;88318;88895.55552222327;1056.6513050693252;1085.7394225385692;1101.7245688577855;1115.754534002518;1119.8544189255344;16364.864462169427;16647.66704665924;16907.492775216764;17157.485275441755;17611.911940440215;39769.20461590694;39831;40138;40756;41862.16275674409;53566;53999.730001349744;55909.440905590425;55950.44049559452;56877.431225687214;7428.1086269647985;7461.403087753075;7609.771706848804;7822.843543129219;7895.526268423913;12547.184433011826;12695.42870570808;13035.413406396545;13140.60578182656;13494.865051349361;10879.891201088205;11133.165012623924;11175.776484470103;11243.189202699325;11482.712932176762;5999;8107.067687216046;11141.554337826396;11236.381998990135;11303.265287756269;11070.833937490783;11150.717667468345;11495.885041149482;11672.066234701373;11862.940685296864;7618.5809780462605;7675.961620191863;7697.191794861459;7759.262870027338;7992.120866704774;8563.357748169037;10777;11056.226064175431;11089.778204436028;11683.474243658886 23:17:50 INFO - PROCESS | 13092 | |3;mootools.html;1368.7399394115118;1371.1019282370048;1375.2779790609923;1390.9930450347747;1392.575264544314;8062.395320350975;8436.451630644004;8520.062793092755;8521.233089021995;8536.103709110546;7394.778156655296;7595.582242976634;7600.847983040336;7778.105517865433;8318.376121790874;2052.9794702052973;2237.8321625878125;2299.6895419118373;2302.0561569756405;2332.2187067332466;2316.2819525946925;2334.9883250583725;2337.3689103941924;2339.6022676145094;2340.6137987232173;2207.5915955548194;2256.334381357495;2258.1757658454626;2258.7402448718444;2263.4341414646337;2013.1645367172605;2081.947951301222;2200.251914349113;2265.954680906389;2267.535155293161;2247.9662805057937;2247.9996401601293;2276.4195130241683;2277.6241920083166;2281.7376001759844;8425.452345597516;8490.490570565786;8493.617787199591;8498.660053597849;8505.914940850636;5019.472955339674;5222.843314700565;5223.71269580173;5226.137687281594;5233.502817232356;3154.858031388593;3177.1104090854597;3389.237421580149;3391.304782519602;3399.762016658835;1234.5925844471303;1283.8908692761127;1309.4238535044549;1342.194683190088;1345.3811246826472;11611.07111431092;11627.825582616182;11629.47667354971;11630.825537616942;11648.009919156882;4324.935125973113;4553.408056952641;4614.307853821886;4630.513796051433;4685.625149987993;9736.318457707892;9739.610415583298;9753.366031207948;9755.414675119515;9777.46223957675;5079;5085.949140508622;5091.1854103343285;5092.26162206478;5096.05722941257;4668.183067963161;4700.55344742249;4752.263399173134;4775.474697783241;4813.109574728616;3658.487811706363;3806.0484878780303;3821.6751576115785;3979.621935916083;4000.8599699010397;1262.4697626996683;1279.9232046077266;1290.8386451693539;1291.7739395605731;1303.3483258370813;925.7454200094893;942.3686130292721;953.1231267234161;955.7753927826955;970.7427531704104;5494.933749025772;5866.73599688015;5934.3175534814045;6091.543134264948;6138.631682099089;940.4968341937167;944.0269836042704;947.9905200947902;963.2871674960479;973.2311473935653;3854.2869569129803;3885.242377736314;3939.8227079780904;3948.940765888514;3991.1818077294083;10082.24383171265;10324;10329.586816527404;10345.068943794944;10398;10265.332753371007;10301.484925753683;10335.94832025835;10337.37975721459;10353.327033742782;7489.550626962399;7795.259450352208;7804.3366313862825;7902;7930.801729956797;5837.854053648693;6024.457798798129;6034.426729460694;6104.969475152596;6144.78493252734;9440.763980900532;9485.66800161991;9486.193673537826;9552.140307372092;9574.808503830023;9317.208037316634;9707.854382184132;9726.70819875405;9733.318667693477;9812;7439.293267139613;7526.435517336112;7541;7545.434092442979;7577.659005344663;7247.528910621004;7516;7646.732364367221;7799.18108598589;7963;7287.27127287267;7295.598742069237;7326.670299836626;7361;7485.251474852471;7283.016792732913;7389.334959853639;7399.815004624712;7403.333699967029;7456.552606843607;6369.936300636934;6489.9026514603265;6561.114249576437;6642.535022548568;6653.035309879943;4836.395450568679;4897.338859253955;4952.455229924777;4977.502249774993;5017.648764586444;4826.1795494766675;4833.613310935187;4887.486813884491;4901.656884018227;4932.161532539405 23:17:50 INFO - PROCESS | 13092 | |4;prototype.html;377.58465687743484;388.5318191579147;392.79574621196997;392.7996721671947;403.17751786355836;5165.173572228444;5283.339582552181;5289.576833853293;5294.83513627002;5309.973450132754;4990.700557966525;5050.97960815673;5124.282600435942;5159.684280508474;5201.488994928427;4136.834526618932;4143.425498310653;4154.605312495323;4176.770277634729;4187.581241875803;4140.585941405865;4161.08590048578;4184.832606695729;4198.727082739628;4199.685023623225;3886.8639597613947;4008.83964641414;4149.170165966803;4154.252234597771;4183.728057676241;4167.229062623409;4174.248635245654;4176.18564379946;4187.371894215892;4192.24539582875;3762;3774.7165963572515;3816.46569480273;3822.4075268333454;3822.789746563938;4899.28960300758;5053.191489361721;5075.213341932006;5083.38999320084;5104.591632669378;4640.025594625133;4647.465541462708;4676.789544470473;4685.765711714401;4706.764661766898;5451.073317536028;5458.781648734046;5473.507384335429;5476.521339238423;5499.7250137493365;3748.8687895923513;3929.44987701722;3961.425593288957;3963.207358528306;3966.484357033596;5628.605997580171;5629.155626656035;5634.690092044977;5635.098384258499;5641.407652196543;4006.2788698034633;4028.4964379452567;4031.7984100794843;4035.2534781065597;4043.0903046814233;3907;4106.1582375612925;4108.732932359387;4111.527174374985;4126.926998980287;4628.490866004737;4648.767561621906;4651.86044418668;4652.651051171176;4668.976655116703;5431.375391829911;5432.093829801038;5439.48324909133;5492.917606235909;5513.283273174462;1364.5633397312963;1372.8536671878894;1384.7992041153975;1393.512270705245;1395.6789938314243;956.5599824081;959.6833045095102;962.5668449197889;962.6630679262203;978.9706308810747;5065.062963351792;5089.898202035939;5124.9743751281;5147.510986456281;5167.9224811627855;5368.543673787772;5370.516653501204;5464.9180262296095;5477.945220547823;5544.279243698294;4877.951220487821;4985.601151907838;5060.178335799109;5079.847604571869;5113.744312784346;2858.9714102858707;2877.589980909381;2883.9279018024718;2891.828809332224;2894.6671132819574;5494.2857428534035;5674.290713660793;5683.914741278884;5701.458361455655;5729.88540229193;5716.771329146787;5733.387965581657;5753.165790960287;5763.942360576424;5782.739776710058;5870.882582348415;5881.941250574938;5886.440788125035;5899.823005309848;5928.762849485972;5535.3634332050715;5589.161625756169;5602.719864006702;5613.1860880171325;5723.395854885934;5548.9722551386985;5562.5549956002615;5574.91637625444;5594.258813181593;5612.747426365741;5674.631148975303;5872.383399742966;5925.377835327228;5928.466438020599;5977.9402205977385;3993.5407428146136;4031.91936161281;4036.172584620087;4037.8788636340955;4041.6968727346157;5424.728763561885;5496.6701997880255;5595.916061259166;5603.691796951207;5635.295588051494;5509.173623956439;5517.5585953124455;5540.199435183662;5549.250851135045;5565.332160140809;5535.25274087993;5542.5565954722715;5555.611107222456;5558.499735023867;5580.581456390706;5298.993191293642;5441.619086664054;5546.916796247979;5558.277423934863;5567.053600887778;5495.422980586981;5511.751971161388;5583.804566840141;5586.86032849182;5604.103343465026;5179.81870634526;5365.168398898177;5425.891482170414;5430.83707488776;5487.314085739283 23:17:50 INFO - PROCESS | 13092 | |5;yui.html;744.2743325257877;744.7840126363355;745.4148493432657;754.3474894216505;783.5886159766122;13936.581902542915;14789.334479948388;15274.618134546641;15606.531804045897;15614.921925390358;15934.601634959132;15954.680906381865;16028.43900463484;16466.176691165423;16683.83316166838;798.6845196147518;822.9588520573977;823.2426167925523;824.1264259884541;825.5665775467897;686.3136863136863;702.9472789540779;791.9089304729972;798.2576204130156;803.5821372886096;862.3015357560365;868.1405408645459;869.5912920927154;869.9086595907434;870.2211520688979;865.0700496965787;865.7619154732467;865.9610317535726;866.6316815353481;866.8439680857443;529.9443558426349;547.1764993684519;603.5443240353549;629.295189387884;632.8196464007782;15051.924740376338;15076.170810605412;15092.5472235832;15095.169765662884;15124.773128403083;13544.119632223874;13712.65718357049;13732.382042808098;13741.450341986309;13928;12937.547185848543;13089.149205301721;13127.606171814861;13156.868431315661;13389.129706569041;4379.978100109511;4398.652862666833;4410.2723050696595;4419.690621656486;4480.081583275421;75325.62337188279;77975.61012194959;78158.60920695416;78874;79238.6038069806;10171.745706357253;10249.538770755335;10284.794304114026;10326.173906087495;10497;20544.486387840425;20573.79426205719;20595.176192952113;20631.93680631925;20705.792942070686;7981.40139489529;8079.4344395892895;8269.29710974562;8316.875246871301;8365.372597055246;4074.124063326399;4237.449131612871;4348.891277718082;4479.10417916418;4578.107269082497;3295.5880514935634;3383.5263063171174;3385.4583266677214;3396.0491062502538;3401.9829900850336;634.7048622390611;638.47644931156;641.1600802948153;657.2113463843311;658.6530545035414;610.2951091489336;625.0936142593258;629.1883470323323;630.8485963368759;646.5344951634816;9545.093216144454;9569.856452153223;9600.519974001412;9856.901430985597;10043.648472303581;689.8620275944832;697.2434908124742;724.873147199238;733.318014246745;736.1865139021411;4556.635469162459;4699.882502937454;4723.102610503994;4738.502457242009;4747.098051370229;73914.6304268486;74952;76529.23470765332;77563;79601.60199198966;56228.15657765137;63532.36467635357;66201;67449;67461;61642.69178654078;62011.6899415509;62738;63258;63424.365756341846;9451.527423628928;9908.157806586232;10134.391936483835;10435.269531132748;10500.789984200426;40179.59820401759;40296.597034029284;41542.376864347665;41562.58437415587;41945.37081943834;53406.73296633647;54044.72977635087;55325.44674553203;55442.44557554373;56012;13295.20228786276;13660.453581857011;13782.173069615856;13860.930695346862;14038.368273427515;15232.543023709306;15292.617684557976;15368.769468458206;15447.382104715685;15468.5359439217;11651.650450486499;14460.421583136556;14463.566093017227;14732.77900831467;15378.077315361114;13575.79636305476;14024.29878506091;14438.85561144375;14716.705665886837;14739.484118055816;13033.608991730263;13577.321133943065;13624.93187534056;13718.65703357424;13880.444782209002;10817.513211905325;10937.234393592373;10957.543491972227;11312.660620181408;11453;10753.247272690836;11001.61494347694;11052.171087168334;11103.50034248477;11104.722381940515 23:17:50 INFO - PROCESS | 13092 | __end_tp_report 23:17:50 INFO - PROCESS | 13092 | __start_cc_report 23:17:50 INFO - PROCESS | 13092 | _x_x_mozilla_cycle_collect,1219 23:17:50 INFO - PROCESS | 13092 | __end_cc_report 23:17:50 INFO - PROCESS | 13092 | __startTimestamp1483082270180__endTimestamp 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | ------- Summary: start ------- 23:17:50 INFO - PROCESS | 13092 | Number of tests: 6 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#0] dojo.html Cycles:165 Average:5352.26 Median:5590.08 stddev:619.73 (11.1%) stddev-sans-first:596.81 23:17:50 INFO - PROCESS | 13092 | Values: 3139.1 3163.8 3173.6 3199.0 3218.1 5251.8 5298.5 5338.4 5383.9 5399.3 5333.6 5339.8 5346.4 5402.2 5451.0 4814.9 4840.2 4880.8 4890.6 4922.4 4974.5 4982.4 4987.2 4989.1 4996.9 4762.3 4778.2 4808.5 4942.8 4966.9 4872.3 4880.0 4881.7 4914.8 4935.1 4825.5 4857.4 4888.0 4899.5 4927.7 5264.3 5274.2 5398.4 5400.1 5404.8 5707.5 5766.8 5767.4 5811.0 5866.8 5834.6 5835.0 5836.7 5846.8 5851.5 5229.1 5414.6 5419.1 5427.3 5427.5 5823.7 5835.4 5847.8 5862.1 5987.1 5624.9 5675.9 5683.3 5684.5 5690.7 5743.5 5753.4 5780.9 5793.0 5806.5 5626.1 5645.9 5661.2 5757.4 5783.4 5702.7 5703.5 5705.2 5706.4 5706.6 4344.6 4347.0 4349.0 4352.2 4382.7 3839.9 3841.9 3855.6 3858.4 3879.4 5472.4 5472.9 5473.5 5476.5 5512.4 5731.2 5764.7 5769.6 5782.2 5782.5 5343.8 5376.4 5426.5 5453.2 5462.2 4837.2 4838.4 4840.3 4861.7 4882.1 6046.3 6049.5 6054.2 6056.9 6060.0 5481.3 5513.4 5713.8 5803.4 5866.4 6006.2 6078.4 6079.8 6133.4 6137.0 5692.8 5732.1 5733.1 5743.4 5746.2 5683.7 5734.1 5773.6 5826.3 5838.2 5552.3 5573.7 5594.0 5597.2 5641.7 5591.6 5598.9 5632.1 5652.7 5662.9 5496.1 5503.3 5552.7 5640.7 5648.2 5670.9 5675.8 5728.7 5758.9 5796.6 5590.1 5620.1 5636.5 5639.6 5640.7 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#1] ext.html Cycles:180 Average:55613.02 Median:33817.47 stddev:109873.19 (324.9%) stddev-sans-first:110103.63 23:17:50 INFO - PROCESS | 13092 | Values: 549.3 583.3 587.5 592.7 593.2 32437.0 41165.4 42246.4 42387.8 45497.5 43883.8 44141.8 44183.3 44192.3 44256.3 8837.6 9023.5 9025.2 9032.0 9032.0 8687.1 8955.8 10536.6 10794.9 10816.4 10742.6 10768.2 10808.3 10816.6 10819.7 10760.8 10777.2 10818.6 10831.7 10845.3 10581.4 10654.4 10660.1 10662.0 10662.6 40955.8 41051.6 41157.6 41207.4 41273.0 3974.7 4057.7 4109.1 4114.3 4117.2 30458.4 30558.3 30755.8 30759.5 31372.7 4104.9 4321.9 4325.7 4326.8 4373.4 321093.0 321627.0 321748.4 322012.0 322275.0 50175.0 50176.0 50302.7 50345.5 50366.0 57396.8 57512.4 57515.7 57571.4 57609.7 15919.3 16334.2 16382.9 16390.9 16391.2 11429.0 11699.2 12556.2 12619.8 12620.1 15509.8 15770.6 16092.6 16282.9 16367.8 6584.6 6626.2 6658.2 6727.9 6731.9 912.4 919.9 922.6 924.9 932.7 29253.0 31140.5 31245.8 31409.5 31707.8 1058.4 1062.4 1067.1 1103.4 1195.0 15894.3 16329.9 16506.8 16569.3 16671.8 482589.0 484664.0 486197.0 486923.0 487970.0 49894.8 49970.8 49998.5 50031.5 50078.5 1369.5 1404.2 1416.5 1423.7 1433.5 28680.7 29703.0 29858.3 30613.7 30710.5 46032.8 48240.3 48760.5 48803.3 53158.7 416796.0 420833.1 420865.0 421223.0 422064.0 23016.2 23618.2 23980.9 25618.9 25784.2 35355.8 38545.8 38819.2 38939.8 39367.0 33546.7 33663.0 33944.2 34099.8 34143.0 33372.8 33545.8 33704.7 33802.3 33832.7 33229.0 33942.8 33956.0 34229.3 34552.8 28289.9 30518.4 30757.5 30807.4 30853.9 23096.9 23307.9 23538.7 25339.5 26640.9 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#2] jquery.html Cycles:180 Average:26240.31 Median:20582.95 stddev:70436.50 (342.2%) stddev-sans-first:70607.32 23:17:50 INFO - PROCESS | 13092 | Values: 378.7 382.3 396.1 396.8 409.9 14516.7 14912.9 16945.8 18950.8 19102.0 15652.6 17957.7 18299.7 18583.1 18661.8 5425.5 5466.4 5664.7 5667.8 5671.2 5495.5 5546.8 5568.1 5584.1 5597.7 4247.9 4492.0 5560.5 5903.4 6159.3 6031.1 6169.4 6248.9 6261.6 6264.8 5441.4 5478.6 5484.6 5487.3 5487.3 21416.5 21500.5 21884.8 21902.9 21953.6 2807.8 2870.3 2871.3 2872.9 2902.3 13760.7 13972.2 14073.4 14179.1 14320.7 1922.7 1969.4 1980.4 1997.5 2014.7 76102.9 76670.2 77121.6 77173.6 77886.6 10232.6 10314.3 10485.3 10523.9 10540.2 14587.8 14590.9 14596.0 14602.4 14611.6 7620.5 7837.2 7915.1 8041.2 8124.8 7567.5 7662.7 7667.2 7840.4 7855.9 6452.9 6471.8 6482.2 6529.2 6664.9 1753.8 1769.6 1770.2 1790.1 1824.7 449.2 449.2 458.2 864.4 876.5 11094.8 11361.0 11562.7 11777.4 11833.8 488.7 490.1 498.6 879.0 929.6 9558.8 9574.5 9872.3 9974.0 10897.1 418332.7 420040.0 425658.0 434327.0 434401.0 64619.4 78812.0 82327.6 88318.0 88895.6 1056.7 1085.7 1101.7 1115.8 1119.9 16364.9 16647.7 16907.5 17157.5 17611.9 39769.2 39831.0 40138.0 40756.0 41862.2 53566.0 53999.7 55909.4 55950.4 56877.4 7428.1 7461.4 7609.8 7822.8 7895.5 12547.2 12695.4 13035.4 13140.6 13494.9 10879.9 11133.2 11175.8 11243.2 11482.7 5999.0 8107.1 11141.6 11236.4 11303.3 11070.8 11150.7 11495.9 11672.1 11862.9 7618.6 7676.0 7697.2 7759.3 7992.1 8563.4 10777.0 11056.2 11089.8 11683.5 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#3] mootools.html Cycles:180 Average:5543.36 Median:5049.24 stddev:3078.79 (61.0%) stddev-sans-first:3071.44 23:17:50 INFO - PROCESS | 13092 | Values: 1368.7 1371.1 1375.3 1391.0 1392.6 8062.4 8436.5 8520.1 8521.2 8536.1 7394.8 7595.6 7600.8 7778.1 8318.4 2053.0 2237.8 2299.7 2302.1 2332.2 2316.3 2335.0 2337.4 2339.6 2340.6 2207.6 2256.3 2258.2 2258.7 2263.4 2013.2 2081.9 2200.3 2266.0 2267.5 2248.0 2248.0 2276.4 2277.6 2281.7 8425.5 8490.5 8493.6 8498.7 8505.9 5019.5 5222.8 5223.7 5226.1 5233.5 3154.9 3177.1 3389.2 3391.3 3399.8 1234.6 1283.9 1309.4 1342.2 1345.4 11611.1 11627.8 11629.5 11630.8 11648.0 4324.9 4553.4 4614.3 4630.5 4685.6 9736.3 9739.6 9753.4 9755.4 9777.5 5079.0 5085.9 5091.2 5092.3 5096.1 4668.2 4700.6 4752.3 4775.5 4813.1 3658.5 3806.0 3821.7 3979.6 4000.9 1262.5 1279.9 1290.8 1291.8 1303.3 925.7 942.4 953.1 955.8 970.7 5494.9 5866.7 5934.3 6091.5 6138.6 940.5 944.0 948.0 963.3 973.2 3854.3 3885.2 3939.8 3948.9 3991.2 10082.2 10324.0 10329.6 10345.1 10398.0 10265.3 10301.5 10335.9 10337.4 10353.3 7489.6 7795.3 7804.3 7902.0 7930.8 5837.9 6024.5 6034.4 6105.0 6144.8 9440.8 9485.7 9486.2 9552.1 9574.8 9317.2 9707.9 9726.7 9733.3 9812.0 7439.3 7526.4 7541.0 7545.4 7577.7 7247.5 7516.0 7646.7 7799.2 7963.0 7287.3 7295.6 7326.7 7361.0 7485.3 7283.0 7389.3 7399.8 7403.3 7456.6 6369.9 6489.9 6561.1 6642.5 6653.0 4836.4 4897.3 4952.5 4977.5 5017.6 4826.2 4833.6 4887.5 4901.7 4932.2 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#4] prototype.html Cycles:180 Average:4621.52 Median:5190.65 stddev:1342.90 (25.9%) stddev-sans-first:1308.35 23:17:50 INFO - PROCESS | 13092 | Values: 377.6 388.5 392.8 392.8 403.2 5165.2 5283.3 5289.6 5294.8 5310.0 4990.7 5051.0 5124.3 5159.7 5201.5 4136.8 4143.4 4154.6 4176.8 4187.6 4140.6 4161.1 4184.8 4198.7 4199.7 3886.9 4008.8 4149.2 4154.3 4183.7 4167.2 4174.2 4176.2 4187.4 4192.2 3762.0 3774.7 3816.5 3822.4 3822.8 4899.3 5053.2 5075.2 5083.4 5104.6 4640.0 4647.5 4676.8 4685.8 4706.8 5451.1 5458.8 5473.5 5476.5 5499.7 3748.9 3929.4 3961.4 3963.2 3966.5 5628.6 5629.2 5634.7 5635.1 5641.4 4006.3 4028.5 4031.8 4035.3 4043.1 3907.0 4106.2 4108.7 4111.5 4126.9 4628.5 4648.8 4651.9 4652.7 4669.0 5431.4 5432.1 5439.5 5492.9 5513.3 1364.6 1372.9 1384.8 1393.5 1395.7 956.6 959.7 962.6 962.7 979.0 5065.1 5089.9 5125.0 5147.5 5167.9 5368.5 5370.5 5464.9 5477.9 5544.3 4878.0 4985.6 5060.2 5079.8 5113.7 2859.0 2877.6 2883.9 2891.8 2894.7 5494.3 5674.3 5683.9 5701.5 5729.9 5716.8 5733.4 5753.2 5763.9 5782.7 5870.9 5881.9 5886.4 5899.8 5928.8 5535.4 5589.2 5602.7 5613.2 5723.4 5549.0 5562.6 5574.9 5594.3 5612.7 5674.6 5872.4 5925.4 5928.5 5977.9 3993.5 4031.9 4036.2 4037.9 4041.7 5424.7 5496.7 5595.9 5603.7 5635.3 5509.2 5517.6 5540.2 5549.3 5565.3 5535.3 5542.6 5555.6 5558.5 5580.6 5299.0 5441.6 5546.9 5558.3 5567.1 5495.4 5511.8 5583.8 5586.9 5604.1 5179.8 5365.2 5425.9 5430.8 5487.3 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - PROCESS | 13092 | [#5] yui.html Cycles:180 Average:17388.18 Median:4449.40 stddev:21798.87 (489.9%) stddev-sans-first:21824.20 23:17:50 INFO - PROCESS | 13092 | Values: 744.3 744.8 745.4 754.3 783.6 13936.6 14789.3 15274.6 15606.5 15614.9 15934.6 15954.7 16028.4 16466.2 16683.8 798.7 823.0 823.2 824.1 825.6 686.3 702.9 791.9 798.3 803.6 862.3 868.1 869.6 869.9 870.2 865.1 865.8 866.0 866.6 866.8 529.9 547.2 603.5 629.3 632.8 15051.9 15076.2 15092.5 15095.2 15124.8 13544.1 13712.7 13732.4 13741.5 13928.0 12937.5 13089.1 13127.6 13156.9 13389.1 4380.0 4398.7 4410.3 4419.7 4480.1 75325.6 77975.6 78158.6 78874.0 79238.6 10171.7 10249.5 10284.8 10326.2 10497.0 20544.5 20573.8 20595.2 20631.9 20705.8 7981.4 8079.4 8269.3 8316.9 8365.4 4074.1 4237.4 4348.9 4479.1 4578.1 3295.6 3383.5 3385.5 3396.0 3402.0 634.7 638.5 641.2 657.2 658.7 610.3 625.1 629.2 630.8 646.5 9545.1 9569.9 9600.5 9856.9 10043.6 689.9 697.2 724.9 733.3 736.2 4556.6 4699.9 4723.1 4738.5 4747.1 73914.6 74952.0 76529.2 77563.0 79601.6 56228.2 63532.4 66201.0 67449.0 67461.0 61642.7 62011.7 62738.0 63258.0 63424.4 9451.5 9908.2 10134.4 10435.3 10500.8 40179.6 40296.6 41542.4 41562.6 41945.4 53406.7 54044.7 55325.4 55442.4 56012.0 13295.2 13660.5 13782.2 13860.9 14038.4 15232.5 15292.6 15368.8 15447.4 15468.5 11651.7 14460.4 14463.6 14732.8 15378.1 13575.8 14024.3 14438.9 14716.7 14739.5 13033.6 13577.3 13624.9 13718.7 13880.4 10817.5 10937.2 10957.5 11312.7 11453.0 10753.2 11001.6 11052.2 11103.5 11104.7 23:17:50 INFO - PROCESS | 13092 | -------- Summary: end -------- 23:17:50 INFO - PROCESS | 13092 | 23:17:50 INFO - TEST-INFO | 13092: exit 0 23:17:51 INFO - TEST-OK | dromaeo_css | took 1115711ms 23:17:51 INFO - TEST-START | kraken 23:17:51 INFO - operating with platform_type : linux_ 23:17:51 INFO - Initialising browser for kraken test... 23:17:51 INFO - TEST-INFO | started process 13397 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjKRhwv/profile http://localhost:42447/getInfo.html) 23:17:51 INFO - PROCESS | 13397 | 1483082271482 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 23:17:54 INFO - PROCESS | 13397 | __metrics Screen width/height:1600/1200 23:17:54 INFO - PROCESS | 13397 | colorDepth:24 23:17:54 INFO - PROCESS | 13397 | Browser inner width/height: 1024/697 23:17:54 INFO - PROCESS | 13397 | __metrics 23:17:55 INFO - PROCESS | 13397 | 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:17:55 INFO - PROCESS | 13397 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:17:56 INFO - PROCESS | 13397 | console.error: PushService: 23:17:56 INFO - PROCESS | 13397 | stateChangeProcessEnqueue: Error transitioning state 23:17:56 INFO - PROCESS | 13397 | UnknownError 23:17:56 INFO - TEST-INFO | 13397: exit 0 23:17:56 INFO - Browser initialized. 23:17:56 INFO - Running cycle 1/1 for kraken test... 23:17:56 INFO - TEST-INFO | started process 13478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjKRhwv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:08 INFO - PROCESS | 13478 | RSS: Main: 146751488 23:18:08 INFO - PROCESS | 13478 | 23:18:11 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42447/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:11 INFO - PROCESS | 13478 | RSS: Main: 202002432 23:18:11 INFO - PROCESS | 13478 | 23:18:14 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42447/tests/kraken/driver.html?testName=audio-dft) 23:18:15 INFO - PROCESS | 13478 | RSS: Main: 181260288 23:18:15 INFO - PROCESS | 13478 | 23:18:18 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42447/tests/kraken/driver.html?testName=audio-fft) 23:18:18 INFO - PROCESS | 13478 | RSS: Main: 262774784 23:18:18 INFO - PROCESS | 13478 | 23:18:20 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42447/tests/kraken/driver.html?testName=audio-oscillator) 23:18:21 INFO - PROCESS | 13478 | RSS: Main: 174948352 23:18:21 INFO - PROCESS | 13478 | 23:18:23 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:18:24 INFO - PROCESS | 13478 | RSS: Main: 178348032 23:18:24 INFO - PROCESS | 13478 | 23:18:27 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42447/tests/kraken/driver.html?testName=imaging-darkroom) 23:18:27 INFO - PROCESS | 13478 | RSS: Main: 217071616 23:18:27 INFO - PROCESS | 13478 | 23:18:31 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42447/tests/kraken/driver.html?testName=imaging-desaturate) 23:18:31 INFO - PROCESS | 13478 | RSS: Main: 217608192 23:18:31 INFO - PROCESS | 13478 | 23:18:34 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42447/tests/kraken/driver.html?testName=json-parse-financial) 23:18:34 INFO - PROCESS | 13478 | RSS: Main: 215826432 23:18:34 INFO - PROCESS | 13478 | 23:18:36 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:18:37 INFO - PROCESS | 13478 | RSS: Main: 178343936 23:18:37 INFO - PROCESS | 13478 | 23:18:38 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:18:39 INFO - PROCESS | 13478 | RSS: Main: 185212928 23:18:39 INFO - PROCESS | 13478 | 23:18:41 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:18:42 INFO - PROCESS | 13478 | RSS: Main: 193912832 23:18:42 INFO - PROCESS | 13478 | 23:18:44 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:18:45 INFO - PROCESS | 13478 | RSS: Main: 203198464 23:18:45 INFO - PROCESS | 13478 | 23:18:48 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:18:48 INFO - PROCESS | 13478 | RSS: Main: 199917568 23:18:48 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | Cycle 1(1): loaded http://localhost:42447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42447/tests/kraken/driver.html?testName=ai-astar) 23:18:50 INFO - PROCESS | 13478 | RSS: Main: 250818560 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | __start_tp_report 23:18:50 INFO - PROCESS | 13478 | _x_x_mozilla_page_load 23:18:50 INFO - PROCESS | 13478 | _x_x_mozilla_page_load_details 23:18:50 INFO - PROCESS | 13478 | |i|pagename|runs| 23:18:50 INFO - PROCESS | 13478 | |0;ai-astar;108;110;110;109;112;114;111;110;111;110 23:18:50 INFO - PROCESS | 13478 | |1;audio-beat-detection;145;120;132;155;125;118;118;119;148;124 23:18:50 INFO - PROCESS | 13478 | |2;audio-dft;161;161;161;161;161;160;163;161;162;162 23:18:50 INFO - PROCESS | 13478 | |3;audio-fft;76;76;76;112;76;82;75;75;90;76 23:18:50 INFO - PROCESS | 13478 | |4;audio-oscillator;83;83;112;83;83;83;105;84;83;112 23:18:50 INFO - PROCESS | 13478 | |5;imaging-gaussian-blur;118;116;117;117;116;117;116;117;117;117 23:18:50 INFO - PROCESS | 13478 | |6;imaging-darkroom;146;147;147;146;146;146;147;147;146;146 23:18:50 INFO - PROCESS | 13478 | |7;imaging-desaturate;96;95;96;96;96;96;96;95;95;95 23:18:50 INFO - PROCESS | 13478 | |8;json-parse-financial;52;52;53;51;51;52;52;53;53;52 23:18:50 INFO - PROCESS | 13478 | |9;json-stringify-tinderbox;67;67;67;66;67;67;67;68;67;67 23:18:50 INFO - PROCESS | 13478 | |10;stanford-crypto-aes;82;82;81;82;81;82;82;84;88;85 23:18:50 INFO - PROCESS | 13478 | |11;stanford-crypto-ccm;129;126;135;138;127;129;125;125;167;154 23:18:50 INFO - PROCESS | 13478 | |12;stanford-crypto-pbkdf2;166;172;173;166;170;169;172;173;168;166 23:18:50 INFO - PROCESS | 13478 | |13;stanford-crypto-sha256-iterative;65;64;63;63;63;66;62;64;64;62 23:18:50 INFO - PROCESS | 13478 | __end_tp_report 23:18:50 INFO - PROCESS | 13478 | __start_cc_report 23:18:50 INFO - PROCESS | 13478 | _x_x_mozilla_cycle_collect,1587 23:18:50 INFO - PROCESS | 13478 | __end_cc_report 23:18:50 INFO - PROCESS | 13478 | __startTimestamp1483082330726__endTimestamp 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | ------- Summary: start ------- 23:18:50 INFO - PROCESS | 13478 | Number of tests: 14 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.50 stddev:1.65 (1.5%) stddev-sans-first:1.48 23:18:50 INFO - PROCESS | 13478 | Values: 108.0 110.0 110.0 109.0 112.0 114.0 111.0 110.0 111.0 110.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:128.50 stddev:13.93 (10.8%) stddev-sans-first:13.74 23:18:50 INFO - PROCESS | 13478 | Values: 145.0 120.0 132.0 155.0 125.0 118.0 118.0 119.0 148.0 124.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 23:18:50 INFO - PROCESS | 13478 | Values: 161.0 161.0 161.0 161.0 161.0 160.0 163.0 161.0 162.0 162.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.73 (15.4%) stddev-sans-first:12.28 23:18:50 INFO - PROCESS | 13478 | Values: 76.0 76.0 76.0 112.0 76.0 82.0 75.0 75.0 90.0 76.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:83.00 stddev:12.96 (15.6%) stddev-sans-first:13.41 23:18:50 INFO - PROCESS | 13478 | Values: 83.0 83.0 112.0 83.0 83.0 83.0 105.0 84.0 83.0 112.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 23:18:50 INFO - PROCESS | 13478 | Values: 118.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:18:50 INFO - PROCESS | 13478 | Values: 146.0 147.0 147.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:18:50 INFO - PROCESS | 13478 | Values: 96.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 23:18:50 INFO - PROCESS | 13478 | Values: 52.0 52.0 53.0 51.0 51.0 52.0 52.0 53.0 53.0 52.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 23:18:50 INFO - PROCESS | 13478 | Values: 67.0 67.0 67.0 66.0 67.0 67.0 67.0 68.0 67.0 67.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.00 stddev:2.18 (2.7%) stddev-sans-first:2.29 23:18:50 INFO - PROCESS | 13478 | Values: 82.0 82.0 81.0 82.0 81.0 82.0 82.0 84.0 88.0 85.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:132.00 stddev:14.18 (10.7%) stddev-sans-first:14.84 23:18:50 INFO - PROCESS | 13478 | Values: 129.0 126.0 135.0 138.0 127.0 129.0 125.0 125.0 167.0 154.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:171.00 stddev:2.92 (1.7%) stddev-sans-first:2.80 23:18:50 INFO - PROCESS | 13478 | Values: 166.0 172.0 173.0 166.0 170.0 169.0 172.0 173.0 168.0 166.0 23:18:50 INFO - PROCESS | 13478 | 23:18:50 INFO - PROCESS | 13478 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.60 Median:64.00 stddev:1.26 (2.0%) stddev-sans-first:1.24 23:18:50 INFO - PROCESS | 13478 | Values: 65.0 64.0 63.0 63.0 63.0 66.0 62.0 64.0 64.0 62.0 23:18:50 INFO - PROCESS | 13478 | -------- Summary: end -------- 23:18:50 INFO - PROCESS | 13478 | 23:18:51 INFO - TEST-INFO | 13478: exit 0 23:18:51 INFO - TEST-OK | kraken | took 60782ms 23:18:51 INFO - SUITE-END | took 1176s 23:18:51 INFO - Completed test suite (00:19:36) 23:18:51 INFO - javascript benchmark 23:18:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.1367373972153, 3163.825989570573, 3173.6350319713233, 3199.0, 3218.147190994387, 5251.763670634821, 5298.549623282021, 5338.3860856001575, 5383.892322153554, 5399.298091248133, 5333.573314134869, 5339.786408543654, 5346.438623944478, 5402.243685883969, 5451.04606693828, 4814.879628009301, 4840.177169881129, 4880.755962201893, 4890.633202509826, 4922.384701912261, 4974.452810190876, 4982.402111746577, 4987.202047672373, 4989.077020751154, 4996.875078123039, 4762.261849413347, 4778.187708089633, 4808.543188397097, 4942.802287908497, 4966.900661986776, 4872.3422337984275, 4880.048390563841, 4881.731504767236, 4914.827981020683, 4935.136351138536, 4825.5174482551465, 4857.417109946794, 4888.046830867982, 4899.510048995107, 4927.679700819471, 5264.315638966948, 5274.182501712241, 5398.433164517708, 5400.108982017962, 5404.839425411718, 5707.543396528309, 5766.7981620643495, 5767.423257674241, 5810.970945145247, 5866.823995280149, 5834.5624078194305, 5835.0, 5836.737346819404, 5846.795362162304, 5851.531877449836, 5229.058769421541, 5414.6209765316835, 5419.078756611346, 5427.3215848019, 5427.511523962823, 5823.679697616672, 5835.35811060783, 5847.8245652630485, 5862.14998825168, 5987.072003839412, 5624.943750562524, 5675.943240567624, 5683.2895888014, 5684.54523638108, 5690.715464226855, 5743.454371834752, 5753.367129615739, 5780.855478612984, 5792.957267691856, 5806.535477161817, 5626.099824028136, 5645.943540564624, 5661.235733175969, 5757.366689664133, 5783.421657834183, 5702.714864256771, 5703.486686198262, 5705.1727499512335, 5706.3722990471015, 5706.629069110578, 4344.6089851913475, 4347.0, 4349.011926309811, 4352.216601011848, 4382.715123516961, 3839.865604703822, 3841.9039524012123, 3855.63371479709, 3858.401947698055, 3879.3599056155417, 5472.398036216013, 5472.91790623141, 5473.534749546254, 5476.479734425223, 5512.39363669996, 5731.168980497888, 5764.7117644117625, 5769.624974376652, 5782.190493330937, 5782.45066718661, 5343.786248550092, 5376.354837419534, 5426.457354264542, 5453.209284653703, 5462.180672899097, 4837.153498137882, 4838.371011768448, 4840.322354870321, 4861.708297502161, 4882.12121818069, 6046.27444706638, 6049.51603871698, 6054.182685337511, 6056.909146362808, 6060.030395136757, 5481.287432633732, 5513.393526712139, 5713.8285851424525, 5803.419658034163, 5866.442687944638, 6006.189164462741, 6078.361772014052, 6079.848003799941, 6133.355997620186, 6137.048757442603, 5692.7722891083895, 5732.05421105513, 5733.111367738007, 5743.396943321059, 5746.195532625519, 5683.687397193194, 5734.053881109737, 5773.607784282322, 5826.300843898759, 5838.211841401357, 5552.278203833475, 5573.665580065209, 5593.972030139823, 5597.216389705363, 5641.717914104361, 5591.552675786009, 5598.944010560005, 5632.098864181711, 5652.745626446737, 5662.915056274242, 5496.093144631251, 5503.3395992481155, 5552.666839989613, 5640.661560306231, 5648.209250704823, 5670.914936276042, 5675.77296908119, 5728.713564321684, 5758.884822303614, 5796.62321949072, 5590.077637189818, 5620.1288800236025, 5636.549076073986, 5639.577031722719, 5640.71796410186], "value": 5310.122387695345, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.2859282932188, 583.2767368463107, 587.4888846703369, 592.6532978207748, 593.1636392686312, 32437.0, 41165.38251926223, 42246.366304505456, 42387.78806105973, 45497.54502454983, 43883.78058109705, 44141.7792911035, 44183.33724994128, 44192.3371149433, 44256.33615495762, 8837.646494140226, 9023.548822558878, 9025.18773310402, 9032.0, 9032.0, 8687.087855775148, 8955.776105597379, 10536.578536858517, 10794.946025269863, 10816.351018938849, 10742.624008159715, 10768.192385571074, 10808.297460665071, 10816.567337306498, 10819.729506762355, 10760.784784304271, 10777.245592808507, 10818.621348252773, 10831.729206769814, 10845.295055821385, 10581.418022008864, 10654.4140072296, 10660.093892019187, 10662.040416362486, 10662.573497060108, 40955.795221024004, 41051.589484105076, 41157.58842411597, 41207.38188927169, 41273.0, 3974.682025437958, 4057.7159598828093, 4109.137081212949, 4114.280000999843, 4117.155983023473, 30458.390832183453, 30558.263469162317, 30755.846220768977, 30759.538606920913, 31372.686273137206, 4104.91790164195, 4321.9135617287475, 4325.718828276184, 4326.8269269229195, 4373.409589705381, 321093.0, 321627.0, 321748.3912580446, 322012.0, 322275.0, 50175.0, 50176.0, 50302.74848625733, 50345.49654503481, 50366.0, 57396.834284914556, 57512.424875751545, 57515.71242143762, 57571.42428575744, 57609.711951439975, 15919.283632236577, 16334.183290835648, 16382.918085409736, 16390.918045409937, 16391.180440977903, 11428.971392574707, 11699.23954942926, 12556.18384805003, 12619.810702839464, 12620.053495987837, 15509.844901551065, 15770.605734856721, 16092.620549684698, 16282.918585407235, 16367.754483682755, 6584.637844918576, 6626.171728533934, 6658.201015878126, 6727.865442691119, 6731.932680673131, 912.4479689787582, 919.9264058875274, 922.6125027488471, 924.8843894513186, 932.7388331267256, 29253.0, 31140.532892006184, 31245.843770781, 31409.528857067162, 31707.84146079301, 1058.3914249306526, 1062.4475272858072, 1067.0823092140752, 1103.3545375955157, 1194.9761004779855, 15894.284757185955, 16329.91835040841, 16506.83493165053, 16569.25438355277, 16671.83328166727, 482589.0, 484664.0, 486197.0, 486923.0, 487970.0, 49894.75052624714, 49970.75014624903, 49998.50001499939, 50031.499685003415, 50078.49921500811, 1369.486442584031, 1404.1925892611584, 1416.5113036002356, 1423.665438621923, 1433.4624515806572, 28680.713192867803, 29703.0, 29858.253543661584, 30613.693863061086, 30710.539341909443, 46032.769836150605, 48240.276395854795, 48760.51239487559, 48803.26795098148, 53158.73420632872, 416796.0, 420833.0625121816, 420865.0, 421223.0, 422064.0, 23016.194433194756, 23618.17336393218, 23980.880095599412, 25618.871905640353, 25784.226473205836, 35355.82322088373, 38545.807270963465, 38819.2236155281, 38939.80530097445, 39367.0, 33546.66453335436, 33663.0, 33944.15139621431, 34099.829500852335, 34143.0, 33372.833135835135, 33545.83227083849, 33704.66295337113, 33802.28174900808, 33832.66167338295, 33229.0, 33942.83028584841, 33956.0, 34229.315413692086, 34552.82723586366, 28289.858550707937, 30518.38963220679, 30757.538636920017, 30807.383852323277, 30853.92011279684, 23096.884515577316, 23307.883460582587, 23538.70537120475, 25339.49321013606, 26640.866795665897], "value": 19160.267644920932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.6705566157443, 382.3061144023597, 396.0929471510242, 396.7500474700938, 409.89137878462213, 14516.709665806677, 14912.850871491282, 16945.83054169458, 18950.77836767751, 19102.0, 15652.608684782886, 17957.7306340405, 18299.725504117414, 18583.070846457656, 18661.813381866177, 5425.457454254582, 5466.371367292772, 5664.688442135681, 5667.801626943058, 5671.2060311556415, 5495.53287970523, 5546.77812887484, 5568.136938774496, 5584.106542953128, 5597.692126933017, 4247.936280955788, 4491.955080449203, 5560.499555040045, 5903.380145084769, 6159.260888693363, 6031.125486804389, 6169.352218017133, 6248.9062664060075, 6261.561690681631, 6264.843378915518, 5441.374241962186, 5478.561715062785, 5484.643498172606, 5487.341519017744, 5487.341519017744, 21416.46458838526, 21500.46248843776, 21884.781152188432, 21902.890485547472, 21953.560928781335, 2807.845568493732, 2870.282429392652, 2871.296532349567, 2872.8707208175683, 2902.288939209886, 13760.724785504333, 13972.231527265996, 14073.36669849849, 14179.078359906676, 14320.713585728332, 1922.6731455652432, 1969.4156634924443, 1980.4157773456866, 1997.4806550297028, 2014.6977953306975, 76102.92095962264, 76670.23329766687, 77121.61439192768, 77173.61413192897, 77886.61056694681, 10232.623234332652, 10314.329568578018, 10485.318454300446, 10523.894761052292, 10540.209484288709, 14587.781183282259, 14590.85409145895, 14596.0, 14602.415903363959, 14611.634709132357, 7620.504667196615, 7837.207335817058, 7915.089764677021, 8041.155678653658, 8124.796880078045, 7567.508111972704, 7662.693492260359, 7667.194944530744, 7840.411969102226, 7855.8821617675785, 6452.935470645328, 6471.838204044842, 6482.157319548429, 6529.183852018497, 6664.866702666017, 1753.7632419623442, 1769.637224369027, 1770.194561474526, 1790.1228398084845, 1824.744535764994, 449.19593926871187, 449.2407830765995, 458.1547045700677, 864.386285737121, 876.509154873273, 11094.778104437866, 11361.0343120834, 11562.710932226762, 11777.411129443493, 11833.763324733629, 488.7043338780058, 490.0908814149772, 498.56624736479495, 878.9560521974004, 929.6374413978554, 9558.761030974281, 9574.47340396285, 9872.308938374388, 9974.0, 10897.128229741602, 418332.72500912513, 420040.0, 425658.0, 434327.0, 434401.0, 64619.35380646228, 78812.0, 82327.58836205781, 88318.0, 88895.55552222327, 1056.6513050693252, 1085.7394225385692, 1101.7245688577855, 1115.754534002518, 1119.8544189255344, 16364.864462169427, 16647.66704665924, 16907.492775216764, 17157.485275441755, 17611.911940440215, 39769.20461590694, 39831.0, 40138.0, 40756.0, 41862.16275674409, 53566.0, 53999.730001349744, 55909.440905590425, 55950.44049559452, 56877.431225687214, 7428.1086269647985, 7461.403087753075, 7609.771706848804, 7822.843543129219, 7895.526268423913, 12547.184433011826, 12695.42870570808, 13035.413406396545, 13140.60578182656, 13494.865051349361, 10879.891201088205, 11133.165012623924, 11175.776484470103, 11243.189202699325, 11482.712932176762, 5999.0, 8107.067687216046, 11141.554337826396, 11236.381998990135, 11303.265287756269, 11070.833937490783, 11150.717667468345, 11495.885041149482, 11672.066234701373, 11862.940685296864, 7618.5809780462605, 7675.961620191863, 7697.191794861459, 7759.262870027338, 7992.120866704774, 8563.357748169037, 10777.0, 11056.226064175431, 11089.778204436028, 11683.474243658886], "value": 8688.6646660636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.7399394115118, 1371.1019282370048, 1375.2779790609923, 1390.9930450347747, 1392.575264544314, 8062.395320350975, 8436.451630644004, 8520.062793092755, 8521.233089021995, 8536.103709110546, 7394.778156655296, 7595.582242976634, 7600.847983040336, 7778.105517865433, 8318.376121790874, 2052.9794702052973, 2237.8321625878125, 2299.6895419118373, 2302.0561569756405, 2332.2187067332466, 2316.2819525946925, 2334.9883250583725, 2337.3689103941924, 2339.6022676145094, 2340.6137987232173, 2207.5915955548194, 2256.334381357495, 2258.1757658454626, 2258.7402448718444, 2263.4341414646337, 2013.1645367172605, 2081.947951301222, 2200.251914349113, 2265.954680906389, 2267.535155293161, 2247.9662805057937, 2247.9996401601293, 2276.4195130241683, 2277.6241920083166, 2281.7376001759844, 8425.452345597516, 8490.490570565786, 8493.617787199591, 8498.660053597849, 8505.914940850636, 5019.472955339674, 5222.843314700565, 5223.71269580173, 5226.137687281594, 5233.502817232356, 3154.858031388593, 3177.1104090854597, 3389.237421580149, 3391.304782519602, 3399.762016658835, 1234.5925844471303, 1283.8908692761127, 1309.4238535044549, 1342.194683190088, 1345.3811246826472, 11611.07111431092, 11627.825582616182, 11629.47667354971, 11630.825537616942, 11648.009919156882, 4324.935125973113, 4553.408056952641, 4614.307853821886, 4630.513796051433, 4685.625149987993, 9736.318457707892, 9739.610415583298, 9753.366031207948, 9755.414675119515, 9777.46223957675, 5079.0, 5085.949140508622, 5091.1854103343285, 5092.26162206478, 5096.05722941257, 4668.183067963161, 4700.55344742249, 4752.263399173134, 4775.474697783241, 4813.109574728616, 3658.487811706363, 3806.0484878780303, 3821.6751576115785, 3979.621935916083, 4000.8599699010397, 1262.4697626996683, 1279.9232046077266, 1290.8386451693539, 1291.7739395605731, 1303.3483258370813, 925.7454200094893, 942.3686130292721, 953.1231267234161, 955.7753927826955, 970.7427531704104, 5494.933749025772, 5866.73599688015, 5934.3175534814045, 6091.543134264948, 6138.631682099089, 940.4968341937167, 944.0269836042704, 947.9905200947902, 963.2871674960479, 973.2311473935653, 3854.2869569129803, 3885.242377736314, 3939.8227079780904, 3948.940765888514, 3991.1818077294083, 10082.24383171265, 10324.0, 10329.586816527404, 10345.068943794944, 10398.0, 10265.332753371007, 10301.484925753683, 10335.94832025835, 10337.37975721459, 10353.327033742782, 7489.550626962399, 7795.259450352208, 7804.3366313862825, 7902.0, 7930.801729956797, 5837.854053648693, 6024.457798798129, 6034.426729460694, 6104.969475152596, 6144.78493252734, 9440.763980900532, 9485.66800161991, 9486.193673537826, 9552.140307372092, 9574.808503830023, 9317.208037316634, 9707.854382184132, 9726.70819875405, 9733.318667693477, 9812.0, 7439.293267139613, 7526.435517336112, 7541.0, 7545.434092442979, 7577.659005344663, 7247.528910621004, 7516.0, 7646.732364367221, 7799.18108598589, 7963.0, 7287.27127287267, 7295.598742069237, 7326.670299836626, 7361.0, 7485.251474852471, 7283.016792732913, 7389.334959853639, 7399.815004624712, 7403.333699967029, 7456.552606843607, 6369.936300636934, 6489.9026514603265, 6561.114249576437, 6642.535022548568, 6653.035309879943, 4836.395450568679, 4897.338859253955, 4952.455229924777, 4977.502249774993, 5017.648764586444, 4826.1795494766675, 4833.613310935187, 4887.486813884491, 4901.656884018227, 4932.161532539405], "value": 4483.847428831318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.58465687743484, 388.5318191579147, 392.79574621196997, 392.7996721671947, 403.17751786355836, 5165.173572228444, 5283.339582552181, 5289.576833853293, 5294.83513627002, 5309.973450132754, 4990.700557966525, 5050.97960815673, 5124.282600435942, 5159.684280508474, 5201.488994928427, 4136.834526618932, 4143.425498310653, 4154.605312495323, 4176.770277634729, 4187.581241875803, 4140.585941405865, 4161.08590048578, 4184.832606695729, 4198.727082739628, 4199.685023623225, 3886.8639597613947, 4008.83964641414, 4149.170165966803, 4154.252234597771, 4183.728057676241, 4167.229062623409, 4174.248635245654, 4176.18564379946, 4187.371894215892, 4192.24539582875, 3762.0, 3774.7165963572515, 3816.46569480273, 3822.4075268333454, 3822.789746563938, 4899.28960300758, 5053.191489361721, 5075.213341932006, 5083.38999320084, 5104.591632669378, 4640.025594625133, 4647.465541462708, 4676.789544470473, 4685.765711714401, 4706.764661766898, 5451.073317536028, 5458.781648734046, 5473.507384335429, 5476.521339238423, 5499.7250137493365, 3748.8687895923513, 3929.44987701722, 3961.425593288957, 3963.207358528306, 3966.484357033596, 5628.605997580171, 5629.155626656035, 5634.690092044977, 5635.098384258499, 5641.407652196543, 4006.2788698034633, 4028.4964379452567, 4031.7984100794843, 4035.2534781065597, 4043.0903046814233, 3907.0, 4106.1582375612925, 4108.732932359387, 4111.527174374985, 4126.926998980287, 4628.490866004737, 4648.767561621906, 4651.86044418668, 4652.651051171176, 4668.976655116703, 5431.375391829911, 5432.093829801038, 5439.48324909133, 5492.917606235909, 5513.283273174462, 1364.5633397312963, 1372.8536671878894, 1384.7992041153975, 1393.512270705245, 1395.6789938314243, 956.5599824081, 959.6833045095102, 962.5668449197889, 962.6630679262203, 978.9706308810747, 5065.062963351792, 5089.898202035939, 5124.9743751281, 5147.510986456281, 5167.9224811627855, 5368.543673787772, 5370.516653501204, 5464.9180262296095, 5477.945220547823, 5544.279243698294, 4877.951220487821, 4985.601151907838, 5060.178335799109, 5079.847604571869, 5113.744312784346, 2858.9714102858707, 2877.589980909381, 2883.9279018024718, 2891.828809332224, 2894.6671132819574, 5494.2857428534035, 5674.290713660793, 5683.914741278884, 5701.458361455655, 5729.88540229193, 5716.771329146787, 5733.387965581657, 5753.165790960287, 5763.942360576424, 5782.739776710058, 5870.882582348415, 5881.941250574938, 5886.440788125035, 5899.823005309848, 5928.762849485972, 5535.3634332050715, 5589.161625756169, 5602.719864006702, 5613.1860880171325, 5723.395854885934, 5548.9722551386985, 5562.5549956002615, 5574.91637625444, 5594.258813181593, 5612.747426365741, 5674.631148975303, 5872.383399742966, 5925.377835327228, 5928.466438020599, 5977.9402205977385, 3993.5407428146136, 4031.91936161281, 4036.172584620087, 4037.8788636340955, 4041.6968727346157, 5424.728763561885, 5496.6701997880255, 5595.916061259166, 5603.691796951207, 5635.295588051494, 5509.173623956439, 5517.5585953124455, 5540.199435183662, 5549.250851135045, 5565.332160140809, 5535.25274087993, 5542.5565954722715, 5555.611107222456, 5558.499735023867, 5580.581456390706, 5298.993191293642, 5441.619086664054, 5546.916796247979, 5558.277423934863, 5567.053600887778, 5495.422980586981, 5511.751971161388, 5583.804566840141, 5586.86032849182, 5604.103343465026, 5179.81870634526, 5365.168398898177, 5425.891482170414, 5430.83707488776, 5487.314085739283], "value": 4210.84443915352, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.2743325257877, 744.7840126363355, 745.4148493432657, 754.3474894216505, 783.5886159766122, 13936.581902542915, 14789.334479948388, 15274.618134546641, 15606.531804045897, 15614.921925390358, 15934.601634959132, 15954.680906381865, 16028.43900463484, 16466.176691165423, 16683.83316166838, 798.6845196147518, 822.9588520573977, 823.2426167925523, 824.1264259884541, 825.5665775467897, 686.3136863136863, 702.9472789540779, 791.9089304729972, 798.2576204130156, 803.5821372886096, 862.3015357560365, 868.1405408645459, 869.5912920927154, 869.9086595907434, 870.2211520688979, 865.0700496965787, 865.7619154732467, 865.9610317535726, 866.6316815353481, 866.8439680857443, 529.9443558426349, 547.1764993684519, 603.5443240353549, 629.295189387884, 632.8196464007782, 15051.924740376338, 15076.170810605412, 15092.5472235832, 15095.169765662884, 15124.773128403083, 13544.119632223874, 13712.65718357049, 13732.382042808098, 13741.450341986309, 13928.0, 12937.547185848543, 13089.149205301721, 13127.606171814861, 13156.868431315661, 13389.129706569041, 4379.978100109511, 4398.652862666833, 4410.2723050696595, 4419.690621656486, 4480.081583275421, 75325.62337188279, 77975.61012194959, 78158.60920695416, 78874.0, 79238.6038069806, 10171.745706357253, 10249.538770755335, 10284.794304114026, 10326.173906087495, 10497.0, 20544.486387840425, 20573.79426205719, 20595.176192952113, 20631.93680631925, 20705.792942070686, 7981.40139489529, 8079.4344395892895, 8269.29710974562, 8316.875246871301, 8365.372597055246, 4074.124063326399, 4237.449131612871, 4348.891277718082, 4479.10417916418, 4578.107269082497, 3295.5880514935634, 3383.5263063171174, 3385.4583266677214, 3396.0491062502538, 3401.9829900850336, 634.7048622390611, 638.47644931156, 641.1600802948153, 657.2113463843311, 658.6530545035414, 610.2951091489336, 625.0936142593258, 629.1883470323323, 630.8485963368759, 646.5344951634816, 9545.093216144454, 9569.856452153223, 9600.519974001412, 9856.901430985597, 10043.648472303581, 689.8620275944832, 697.2434908124742, 724.873147199238, 733.318014246745, 736.1865139021411, 4556.635469162459, 4699.882502937454, 4723.102610503994, 4738.502457242009, 4747.098051370229, 73914.6304268486, 74952.0, 76529.23470765332, 77563.0, 79601.60199198966, 56228.15657765137, 63532.36467635357, 66201.0, 67449.0, 67461.0, 61642.69178654078, 62011.6899415509, 62738.0, 63258.0, 63424.365756341846, 9451.527423628928, 9908.157806586232, 10134.391936483835, 10435.269531132748, 10500.789984200426, 40179.59820401759, 40296.597034029284, 41542.376864347665, 41562.58437415587, 41945.37081943834, 53406.73296633647, 54044.72977635087, 55325.44674553203, 55442.44557554373, 56012.0, 13295.20228786276, 13660.453581857011, 13782.173069615856, 13860.930695346862, 14038.368273427515, 15232.543023709306, 15292.617684557976, 15368.769468458206, 15447.382104715685, 15468.5359439217, 11651.650450486499, 14460.421583136556, 14463.566093017227, 14732.77900831467, 15378.077315361114, 13575.79636305476, 14024.29878506091, 14438.85561144375, 14716.705665886837, 14739.484118055816, 13033.608991730263, 13577.321133943065, 13624.93187534056, 13718.65703357424, 13880.444782209002, 10817.513211905325, 10937.234393592373, 10957.543491972227, 11312.660620181408, 11453.0, 10753.247272690836, 11001.61494347694, 11052.171087168334, 11103.50034248477, 11104.722381940515], "value": 7217.566003965738, "unit": "score"}], "name": "dromaeo_css", "value": 7027.7929217179735, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 110.0, 109.0, 112.0, 114.0, 111.0, 110.0, 111.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 120.0, 132.0, 155.0, 125.0, 118.0, 118.0, 119.0, 148.0, 124.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 160.0, 163.0, 161.0, 162.0, 162.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 112.0, 76.0, 82.0, 75.0, 75.0, 90.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 112.0, 83.0, 83.0, 83.0, 105.0, 84.0, 83.0, 112.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.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, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 51.0, 51.0, 52.0, 52.0, 53.0, 53.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 82.0, 81.0, 82.0, 82.0, 84.0, 88.0, 85.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 126.0, 135.0, 138.0, 127.0, 129.0, 125.0, 125.0, 167.0, 154.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 172.0, 173.0, 166.0, 170.0, 169.0, 172.0, 173.0, 168.0, 166.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 63.0, 63.0, 66.0, 62.0, 64.0, 64.0, 62.0], "value": 63.6, "unit": "score"}], "name": "kraken", "value": 1504.1, "alertThreshold": 2.0}]} 23:18:51 INFO - Return code: 0 23:18:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:18:52 INFO - # TBPL SUCCESS # 23:18:52 INFO - Running post-action listener: _resource_record_post_action 23:18:52 INFO - [mozharness: 2016-12-30 07:18:52.037111Z] Finished run-tests step (success) 23:18:52 INFO - Running post-run listener: _resource_record_post_run 23:18:52 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 399785984; Read time: 4; Write time: 334960 23:18:52 INFO - TinderboxPrint: CPU usage
13.1% 23:18:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 23:18:52 INFO - TinderboxPrint: I/O write bytes / time
399,785,984 / 334,960 23:18:52 INFO - TinderboxPrint: CPU idle
8,284.9 (86.9%) 23:18:52 INFO - TinderboxPrint: CPU user
1,199.1 (12.6%) 23:18:52 INFO - TinderboxPrint: Swap in / out
0 / 0 23:18:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105504768; Read time: 0; Write time: 104132 23:18:52 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 294281216; Read time: 0; Write time: 230828 23:18:52 INFO - Running post-run listener: _upload_blobber_files 23:18:52 INFO - Blob upload gear active. 23:18:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:18:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:18:53 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:18:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:18:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:56 INFO - (blobuploader) - INFO - Done attempting. 23:18:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:18:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:57 INFO - (blobuploader) - INFO - Done attempting. 23:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:18:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:58 INFO - (blobuploader) - INFO - Done attempting. 23:18:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:18:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:18:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:58 INFO - (blobuploader) - INFO - Done attempting. 23:18:58 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:58 INFO - Return code: 0 23:18:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:18:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e43783035136d7570632e289146d84d94f9a6918931c0c7c3d6250077dd205196d9950ff24d5c4c8c68c853159a7ecb142aefd69085120f2e9328951c4c2a45c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f4cb6d5fae8e3beb4715d1a445e18e9fd6773073c4e80cbcd1c697aa9aeb289b98b7b183d4fa5db280d3970c08dc4f5b019441ba464531608a8bffc17248832f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e7696d385f37fd7ea2799a25384656b3706e46811398ccf023981e07b3851b6537b1e98cbe5657471f007dd66f71ac2a2e6df6ce15c7ba6d18165dd98fee7749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cb0789b3698278da1d979e8eda20ca3151dee65c7d7b8554c45aa343fac24de3efd91f5d9ad2e4d42397d645dd329c5b9725daf3d6eaca5d1c87aedb180ebe4b"} 23:18:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:18:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:18:58 INFO - Contents: 23:18:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e43783035136d7570632e289146d84d94f9a6918931c0c7c3d6250077dd205196d9950ff24d5c4c8c68c853159a7ecb142aefd69085120f2e9328951c4c2a45c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f4cb6d5fae8e3beb4715d1a445e18e9fd6773073c4e80cbcd1c697aa9aeb289b98b7b183d4fa5db280d3970c08dc4f5b019441ba464531608a8bffc17248832f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e7696d385f37fd7ea2799a25384656b3706e46811398ccf023981e07b3851b6537b1e98cbe5657471f007dd66f71ac2a2e6df6ce15c7ba6d18165dd98fee7749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cb0789b3698278da1d979e8eda20ca3151dee65c7d7b8554c45aa343fac24de3efd91f5d9ad2e4d42397d645dd329c5b9725daf3d6eaca5d1c87aedb180ebe4b"} 23:18:58 INFO - Running post-run listener: copy_logs_to_upload_dir 23:18:58 INFO - Copying logs to upload dir... 23:18:58 INFO - mkdir: /builds/slave/test/build/upload/logs 23:18:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.385080 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-12-29 23:18:58.502833) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 23:18:58.506504) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e43783035136d7570632e289146d84d94f9a6918931c0c7c3d6250077dd205196d9950ff24d5c4c8c68c853159a7ecb142aefd69085120f2e9328951c4c2a45c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f4cb6d5fae8e3beb4715d1a445e18e9fd6773073c4e80cbcd1c697aa9aeb289b98b7b183d4fa5db280d3970c08dc4f5b019441ba464531608a8bffc17248832f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e7696d385f37fd7ea2799a25384656b3706e46811398ccf023981e07b3851b6537b1e98cbe5657471f007dd66f71ac2a2e6df6ce15c7ba6d18165dd98fee7749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cb0789b3698278da1d979e8eda20ca3151dee65c7d7b8554c45aa343fac24de3efd91f5d9ad2e4d42397d645dd329c5b9725daf3d6eaca5d1c87aedb180ebe4b"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011754 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483062620/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e43783035136d7570632e289146d84d94f9a6918931c0c7c3d6250077dd205196d9950ff24d5c4c8c68c853159a7ecb142aefd69085120f2e9328951c4c2a45c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/f4cb6d5fae8e3beb4715d1a445e18e9fd6773073c4e80cbcd1c697aa9aeb289b98b7b183d4fa5db280d3970c08dc4f5b019441ba464531608a8bffc17248832f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e7696d385f37fd7ea2799a25384656b3706e46811398ccf023981e07b3851b6537b1e98cbe5657471f007dd66f71ac2a2e6df6ce15c7ba6d18165dd98fee7749", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/cb0789b3698278da1d979e8eda20ca3151dee65c7d7b8554c45aa343fac24de3efd91f5d9ad2e4d42397d645dd329c5b9725daf3d6eaca5d1c87aedb180ebe4b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 23:18:58.532442) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 23:18:58.532761) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1483081100.865344-926338555 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 23:18:58.615081) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 23:18:58.615391) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 23:18:58.615704) ========= ========= Total master_lag: 0.17 =========