builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-052 starttime: 1483671556.47 results: success (0) buildid: 20170105172950 builduid: bc64962c3b314101bc4837723f692846 revision: 5e63be85c717efb6b5bfd2c4695bbba6d4947344 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.469721) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.470230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.470510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.485250) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.485589) ========= 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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 18:59:17-- 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% 42.9M=0s 2017-01-05 18:59:17 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153064 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.652889) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.653260) ========= 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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020601 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:16.703046) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 18:59:16.703398) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5e63be85c717efb6b5bfd2c4695bbba6d4947344 --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 5e63be85c717efb6b5bfd2c4695bbba6d4947344 --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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 18:59:17,457 truncating revision to first 12 chars 2017-01-05 18:59:17,457 Setting DEBUG logging. 2017-01-05 18:59:17,457 attempt 1/10 2017-01-05 18:59:17,457 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/5e63be85c717?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 18:59:18,455 unpacking tar archive at: mozilla-inbound-5e63be85c717/testing/mozharness/ program finished with exit code 0 elapsedTime=1.376062 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 18:59:18.092860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:18.093188) ========= script_repo_revision: 5e63be85c717efb6b5bfd2c4695bbba6d4947344 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:18.093596) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:18.093856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:59:18.102243) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-05 18:59:18.102531) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:18 INFO - MultiFileLogger online at 20170105 18:59:18 in /builds/slave/test 18:59:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 18:59:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:18 INFO - {'append_to_log': False, 18:59:18 INFO - 'base_work_dir': '/builds/slave/test', 18:59:18 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 18:59:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:18 INFO - 'branch': 'Mozilla-Inbound', 18:59:18 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:18 INFO - 'config_files': ('talos/linux_config.py',), 18:59:18 INFO - 'default_actions': ('clobber', 18:59:18 INFO - 'read-buildbot-config', 18:59:18 INFO - 'download-and-extract', 18:59:18 INFO - 'populate-webroot', 18:59:18 INFO - 'create-virtualenv', 18:59:18 INFO - 'install', 18:59:18 INFO - 'run-tests'), 18:59:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:18 INFO - 'download_minidump_stackwalk': True, 18:59:18 INFO - 'download_symbols': 'ondemand', 18:59:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:18 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:18 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:18 INFO - 'installer_path': 'installer.exe', 18:59:18 INFO - 'log_level': 'info', 18:59:18 INFO - 'log_name': 'talos', 18:59:18 INFO - 'log_to_console': True, 18:59:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:18 INFO - 'opt_config_files': (), 18:59:18 INFO - 'pip_index': False, 18:59:18 INFO - 'sps_profile': False, 18:59:18 INFO - 'sps_profile_interval': 0, 18:59:18 INFO - 'suite': 'dromaeojs-e10s', 18:59:18 INFO - 'system_bits': '32', 18:59:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:18 INFO - 'title': 'talos-linux64-ix-052', 18:59:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:18 INFO - 'use_talos_json': True, 18:59:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:18 INFO - 'work_dir': 'build'} 18:59:18 INFO - [mozharness: 2017-01-06 02:59:18.951364Z] Running clobber step. 18:59:18 INFO - Running pre-action listener: _resource_record_pre_action 18:59:18 INFO - Running main action method: clobber 18:59:18 INFO - rmtree: /builds/slave/test/build 18:59:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:19 INFO - Running post-action listener: _resource_record_post_action 18:59:19 INFO - [mozharness: 2017-01-06 02:59:19.411775Z] Finished clobber step (success) 18:59:19 INFO - [mozharness: 2017-01-06 02:59:19.411875Z] Running read-buildbot-config step. 18:59:19 INFO - Running pre-action listener: _resource_record_pre_action 18:59:19 INFO - Running main action method: read_buildbot_config 18:59:19 INFO - Using buildbot properties: 18:59:19 INFO - { 18:59:19 INFO - "project": "", 18:59:19 INFO - "product": "firefox", 18:59:19 INFO - "script_repo_revision": "production", 18:59:19 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 18:59:19 INFO - "repository": "", 18:59:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 18:59:19 INFO - "buildid": "20170105172950", 18:59:19 INFO - "stage_platform": "linux64-pgo", 18:59:19 INFO - "basedir": "/builds/slave/test", 18:59:19 INFO - "buildnumber": 2213, 18:59:19 INFO - "platform": "ubuntu64_hw", 18:59:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:59:19 INFO - "slavebuilddir": "test", 18:59:19 INFO - "branch": "mozilla-inbound", 18:59:19 INFO - "revision": "5e63be85c717efb6b5bfd2c4695bbba6d4947344", 18:59:19 INFO - "slavename": "talos-linux64-ix-052", 18:59:19 INFO - "builduid": "bc64962c3b314101bc4837723f692846", 18:59:19 INFO - "repo_path": "integration/mozilla-inbound" 18:59:19 INFO - } 18:59:19 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 18:59:19 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 18:59:19 INFO - Running post-action listener: _resource_record_post_action 18:59:19 INFO - [mozharness: 2017-01-06 02:59:19.414244Z] Finished read-buildbot-config step (success) 18:59:19 INFO - [mozharness: 2017-01-06 02:59:19.414333Z] Running download-and-extract step. 18:59:19 INFO - Running pre-action listener: _resource_record_pre_action 18:59:19 INFO - Running main action method: download_and_extract 18:59:19 INFO - mkdir: /builds/slave/test/build/tests 18:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:19 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:59:19 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:59:19 INFO - Downloaded 1361 bytes. 18:59:19 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 18:59:19 INFO - Using the following test package requirements: 18:59:19 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:19 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:19 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:19 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'jsshell-linux-x86_64.zip'], 18:59:19 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:19 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:19 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:19 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:19 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:19 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:19 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:19 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59:19 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/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:59:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:19 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:59:23 INFO - Expected file size: 21834245 18:59:23 INFO - Obtained file size: 21834245 18:59:27 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:59:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 18:59:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:27 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 18:59:28 INFO - Expected file size: 21834245 18:59:28 INFO - Obtained file size: 21834245 18:59:32 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/1483659277/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:59:32 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 18:59:36 INFO - Expected file size: 14403730 18:59:36 INFO - Obtained file size: 14403730 18:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:36 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:59:36 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:59:43 INFO - Downloaded 61452406 bytes. 18:59:43 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:59:43 INFO - mkdir: /builds/slave/test/properties 18:59:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:59:43 INFO - Writing to file /builds/slave/test/properties/build_url 18:59:43 INFO - Contents: 18:59:43 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 18:59:44 INFO - Running post-action listener: _resource_record_post_action 18:59:44 INFO - Running post-action listener: set_extra_try_arguments 18:59:44 INFO - [mozharness: 2017-01-06 02:59:44.187751Z] Finished download-and-extract step (success) 18:59:44 INFO - [mozharness: 2017-01-06 02:59:44.187979Z] Running populate-webroot step. 18:59:44 INFO - Running pre-action listener: _resource_record_pre_action 18:59:44 INFO - Running main action method: populate_webroot 18:59:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:59:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:59:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:59:44 INFO - '--tppagecycles', 18:59:44 INFO - '7'], 18:59:44 INFO - 'tests': ['tsvgm']}}, 18:59:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:59:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:59:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:59:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:59:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['damp', 'tps']}, 18:59:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'tests': ['damp', 'tps']}, 18:59:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['dromaeo_dom']}, 18:59:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:59:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['basic_compositor_video']}, 18:59:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:59:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'other-e10s': {'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['a11yr', 18:59:44 INFO - 'ts_paint', 18:59:44 INFO - 'tpaint', 18:59:44 INFO - 'sessionrestore', 18:59:44 INFO - 'sessionrestore_no_auto_restore', 18:59:44 INFO - 'tabpaint']}, 18:59:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['tsvgx', 18:59:44 INFO - 'tsvgr_opacity', 18:59:44 INFO - 'tart', 18:59:44 INFO - 'tscrollx', 18:59:44 INFO - 'cart', 18:59:44 INFO - 'tsvg_static']}, 18:59:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:59:44 INFO - 'tsvgr_opacity', 18:59:44 INFO - 'tart', 18:59:44 INFO - 'tscrollx', 18:59:44 INFO - 'cart', 18:59:44 INFO - 'tsvg_static']}, 18:59:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'talos_options': ['--disable-e10s'], 18:59:44 INFO - 'tests': ['tp5o']}, 18:59:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:59:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'talos_options': ['--disable-e10s', 18:59:44 INFO - '--xperf_path', 18:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:44 INFO - 'tests': ['tp5n']}, 18:59:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:59:44 INFO - 'talos_options': ['--xperf_path', 18:59:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:44 INFO - 'tests': ['tp5n']}}, 18:59:44 INFO - 'talos.zip': {'path': '', 18:59:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:59:44 INFO - Running post-action listener: _resource_record_post_action 18:59:44 INFO - [mozharness: 2017-01-06 02:59:44.205605Z] Finished populate-webroot step (success) 18:59:44 INFO - [mozharness: 2017-01-06 02:59:44.205694Z] Running create-virtualenv step. 18:59:44 INFO - Running pre-action listener: _resource_record_pre_action 18:59:44 INFO - Running main action method: create_virtualenv 18:59:44 INFO - Creating virtualenv /builds/slave/test/build/venv 18:59:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:59:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:59:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:59:44 INFO - Using real prefix '/usr' 18:59:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:59:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:59:46 INFO - Installing pip.................done. 18:59:46 INFO - Return code: 0 18:59:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:59:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:59:46 INFO - Reading from file tmpfile_stdout 18:59:46 INFO - Output received: 18:59:46 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:59:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:47 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:59:47 INFO - Using env: {'DISPLAY': ':0', 18:59:47 INFO - 'HOME': '/home/cltbld', 18:59:47 INFO - 'LANG': 'en_US.UTF-8', 18:59:47 INFO - 'LANGUAGE': 'en_US:en', 18:59:47 INFO - 'LOGNAME': 'cltbld', 18:59:47 INFO - 'MAIL': '/var/mail/cltbld', 18:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:47 INFO - 'MOZ_NO_REMOTE': '1', 18:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:47 INFO - 'NO_EM_RESTART': '1', 18:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:47 INFO - 'PWD': '/builds/slave/test', 18:59:47 INFO - 'SHELL': '/bin/bash', 18:59:47 INFO - 'SHLVL': '1', 18:59:47 INFO - 'TERM': 'linux', 18:59:47 INFO - 'TMOUT': '86400', 18:59:47 INFO - 'USER': 'cltbld', 18:59:47 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:47 INFO - '_': '/tools/buildbot/bin/python'} 18:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:59:47 INFO - Cleaning up... 18:59:47 INFO - Return code: 0 18:59:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:47 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:59:47 INFO - Using env: {'DISPLAY': ':0', 18:59:47 INFO - 'HOME': '/home/cltbld', 18:59:47 INFO - 'LANG': 'en_US.UTF-8', 18:59:47 INFO - 'LANGUAGE': 'en_US:en', 18:59:47 INFO - 'LOGNAME': 'cltbld', 18:59:47 INFO - 'MAIL': '/var/mail/cltbld', 18:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:47 INFO - 'MOZ_NO_REMOTE': '1', 18:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:47 INFO - 'NO_EM_RESTART': '1', 18:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:47 INFO - 'PWD': '/builds/slave/test', 18:59:47 INFO - 'SHELL': '/bin/bash', 18:59:47 INFO - 'SHLVL': '1', 18:59:47 INFO - 'TERM': 'linux', 18:59:47 INFO - 'TMOUT': '86400', 18:59:47 INFO - 'USER': 'cltbld', 18:59:47 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:47 INFO - '_': '/tools/buildbot/bin/python'} 18:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:47 INFO - Downloading/unpacking psutil>=3.1.1 18:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:59:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:49 INFO - Installing collected packages: psutil 18:59:49 INFO - Running setup.py install for psutil 18:59:49 INFO - building 'psutil._psutil_linux' extension 18:59:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:59:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:59:49 INFO - building 'psutil._psutil_posix' extension 18:59:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:59:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:59:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:50 INFO - Successfully installed psutil 18:59:50 INFO - Cleaning up... 18:59:50 INFO - Return code: 0 18:59:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:59:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:59:50 INFO - Using env: {'DISPLAY': ':0', 18:59:50 INFO - 'HOME': '/home/cltbld', 18:59:50 INFO - 'LANG': 'en_US.UTF-8', 18:59:50 INFO - 'LANGUAGE': 'en_US:en', 18:59:50 INFO - 'LOGNAME': 'cltbld', 18:59:50 INFO - 'MAIL': '/var/mail/cltbld', 18:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:50 INFO - 'MOZ_NO_REMOTE': '1', 18:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:50 INFO - 'NO_EM_RESTART': '1', 18:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:50 INFO - 'PWD': '/builds/slave/test', 18:59:50 INFO - 'SHELL': '/bin/bash', 18:59:50 INFO - 'SHLVL': '1', 18:59:50 INFO - 'TERM': 'linux', 18:59:50 INFO - 'TMOUT': '86400', 18:59:50 INFO - 'USER': 'cltbld', 18:59:50 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:50 INFO - '_': '/tools/buildbot/bin/python'} 18:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:59:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:59:52 INFO - Installing collected packages: mozsystemmonitor 18:59:52 INFO - Running setup.py install for mozsystemmonitor 18:59:52 INFO - Successfully installed mozsystemmonitor 18:59:52 INFO - Cleaning up... 18:59:52 INFO - Return code: 0 18:59:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:59: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')]} 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59: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')]} 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:52 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:59:52 INFO - Using env: {'DISPLAY': ':0', 18:59:52 INFO - 'HOME': '/home/cltbld', 18:59:52 INFO - 'LANG': 'en_US.UTF-8', 18:59:52 INFO - 'LANGUAGE': 'en_US:en', 18:59:52 INFO - 'LOGNAME': 'cltbld', 18:59:52 INFO - 'MAIL': '/var/mail/cltbld', 18:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:52 INFO - 'MOZ_NO_REMOTE': '1', 18:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:52 INFO - 'NO_EM_RESTART': '1', 18:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:52 INFO - 'PWD': '/builds/slave/test', 18:59:52 INFO - 'SHELL': '/bin/bash', 18:59:52 INFO - 'SHLVL': '1', 18:59:52 INFO - 'TERM': 'linux', 18:59:52 INFO - 'TMOUT': '86400', 18:59:52 INFO - 'USER': 'cltbld', 18:59:52 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:52 INFO - '_': '/tools/buildbot/bin/python'} 18:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:52 INFO - Downloading/unpacking jsonschema==2.5.1 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:59:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:59: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 18:59: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 18:59: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 18:59: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 18:59: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 18:59: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 18:59:55 INFO - Downloading functools32-3.2.3-2.tar.gz 18:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:59:55 INFO - warning: no files found matching '*.txt' 18:59:55 INFO - no previously-included directories found matching 'build' 18:59:55 INFO - no previously-included directories found matching 'dist' 18:59:55 INFO - no previously-included directories found matching '.git*' 18:59:55 INFO - Installing collected packages: jsonschema, functools32 18:59:55 INFO - Running setup.py install for functools32 18:59:55 INFO - warning: no files found matching '*.txt' 18:59:55 INFO - no previously-included directories found matching 'build' 18:59:55 INFO - no previously-included directories found matching 'dist' 18:59:55 INFO - no previously-included directories found matching '.git*' 18:59:55 INFO - Successfully installed jsonschema functools32 18:59:55 INFO - Cleaning up... 18:59:55 INFO - Return code: 0 18:59:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:59:55 INFO - Using env: {'DISPLAY': ':0', 18:59:55 INFO - 'HOME': '/home/cltbld', 18:59:55 INFO - 'LANG': 'en_US.UTF-8', 18:59:55 INFO - 'LANGUAGE': 'en_US:en', 18:59:55 INFO - 'LOGNAME': 'cltbld', 18:59:55 INFO - 'MAIL': '/var/mail/cltbld', 18:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:55 INFO - 'MOZ_NO_REMOTE': '1', 18:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:55 INFO - 'NO_EM_RESTART': '1', 18:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:55 INFO - 'PWD': '/builds/slave/test', 18:59:55 INFO - 'SHELL': '/bin/bash', 18:59:55 INFO - 'SHLVL': '1', 18:59:55 INFO - 'TERM': 'linux', 18:59:55 INFO - 'TMOUT': '86400', 18:59:55 INFO - 'USER': 'cltbld', 18:59:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:55 INFO - '_': '/tools/buildbot/bin/python'} 18:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:59:55 INFO - Cleaning up... 18:59:55 INFO - Return code: 0 18:59:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:59:55 INFO - Using env: {'DISPLAY': ':0', 18:59:55 INFO - 'HOME': '/home/cltbld', 18:59:55 INFO - 'LANG': 'en_US.UTF-8', 18:59:55 INFO - 'LANGUAGE': 'en_US:en', 18:59:55 INFO - 'LOGNAME': 'cltbld', 18:59:55 INFO - 'MAIL': '/var/mail/cltbld', 18:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:55 INFO - 'MOZ_NO_REMOTE': '1', 18:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:55 INFO - 'NO_EM_RESTART': '1', 18:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:55 INFO - 'PWD': '/builds/slave/test', 18:59:55 INFO - 'SHELL': '/bin/bash', 18:59:55 INFO - 'SHLVL': '1', 18:59:55 INFO - 'TERM': 'linux', 18:59:55 INFO - 'TMOUT': '86400', 18:59:55 INFO - 'USER': 'cltbld', 18:59:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:55 INFO - '_': '/tools/buildbot/bin/python'} 18:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:55 INFO - Downloading/unpacking blobuploader==1.2.4 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:57 INFO - Downloading blobuploader-1.2.4.tar.gz 18:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:59:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:59:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:58 INFO - Downloading docopt-0.6.1.tar.gz 18:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:59:58 INFO - Installing collected packages: blobuploader, requests, docopt 18:59:58 INFO - Running setup.py install for blobuploader 18:59:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:59:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:59:58 INFO - Running setup.py install for requests 18:59:58 INFO - Running setup.py install for docopt 18:59:59 INFO - Successfully installed blobuploader requests docopt 18:59:59 INFO - Cleaning up... 18:59:59 INFO - Return code: 0 18:59:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:59 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:59:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:59:59 INFO - Using env: {'DISPLAY': ':0', 18:59:59 INFO - 'HOME': '/home/cltbld', 18:59:59 INFO - 'LANG': 'en_US.UTF-8', 18:59:59 INFO - 'LANGUAGE': 'en_US:en', 18:59:59 INFO - 'LOGNAME': 'cltbld', 18:59:59 INFO - 'MAIL': '/var/mail/cltbld', 18:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:59 INFO - 'MOZ_NO_REMOTE': '1', 18:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:59 INFO - 'NO_EM_RESTART': '1', 18:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:59 INFO - 'PWD': '/builds/slave/test', 18:59:59 INFO - 'SHELL': '/bin/bash', 18:59:59 INFO - 'SHLVL': '1', 18:59:59 INFO - 'TERM': 'linux', 18:59:59 INFO - 'TMOUT': '86400', 18:59:59 INFO - 'USER': 'cltbld', 18:59:59 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 18:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:59 INFO - '_': '/tools/buildbot/bin/python'} 18:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:59:59 INFO - Running setup.py (path:/tmp/pip-kK6O0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:59:59 INFO - Running setup.py (path:/tmp/pip-u7gL04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:59:59 INFO - Running setup.py (path:/tmp/pip-7sklaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:59:59 INFO - Running setup.py (path:/tmp/pip-aDFUxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:59:59 INFO - Running setup.py (path:/tmp/pip-BXKvxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:59:59 INFO - Running setup.py (path:/tmp/pip-pN71OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:59:59 INFO - Running setup.py (path:/tmp/pip-xL46nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:59:59 INFO - Running setup.py (path:/tmp/pip-2E4Dru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:59:59 INFO - Running setup.py (path:/tmp/pip-5cABcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:00 INFO - Running setup.py (path:/tmp/pip-krtR11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:00 INFO - Running setup.py (path:/tmp/pip-Mvq2rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:00 INFO - Running setup.py (path:/tmp/pip-GPRL0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:00 INFO - Running setup.py (path:/tmp/pip-ck4oVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:00 INFO - Running setup.py (path:/tmp/pip-Fq4qX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:00 INFO - Running setup.py (path:/tmp/pip-LZDQ1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:00 INFO - Running setup.py (path:/tmp/pip-2pplmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:00 INFO - Running setup.py (path:/tmp/pip-q37h8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:00 INFO - Running setup.py install for manifestparser 19:00:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:00 INFO - Running setup.py install for mozcrash 19:00:00 INFO - Running setup.py install for mozdebug 19:00:00 INFO - Running setup.py install for mozdevice 19:00:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:00:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozfile 19:00:01 INFO - Running setup.py install for mozhttpd 19:00:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozinfo 19:00:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozInstall 19:00:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozleak 19:00:01 INFO - Running setup.py install for mozlog 19:00:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for moznetwork 19:00:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozprocess 19:00:01 INFO - Running setup.py install for mozprofile 19:00:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:01 INFO - Running setup.py install for mozrunner 19:00:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:02 INFO - Running setup.py install for mozscreenshot 19:00:02 INFO - Running setup.py install for moztest 19:00:02 INFO - Running setup.py install for mozversion 19:00:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:02 INFO - Cleaning up... 19:00:02 INFO - Return code: 0 19:00:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', '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 19:00:02 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 19:00:02 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 19:00:02 INFO - Using env: {'DISPLAY': ':0', 19:00:02 INFO - 'HOME': '/home/cltbld', 19:00:02 INFO - 'LANG': 'en_US.UTF-8', 19:00:02 INFO - 'LANGUAGE': 'en_US:en', 19:00:02 INFO - 'LOGNAME': 'cltbld', 19:00:02 INFO - 'MAIL': '/var/mail/cltbld', 19:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:02 INFO - 'MOZ_NO_REMOTE': '1', 19:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:02 INFO - 'NO_EM_RESTART': '1', 19:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:02 INFO - 'PWD': '/builds/slave/test', 19:00:02 INFO - 'SHELL': '/bin/bash', 19:00:02 INFO - 'SHLVL': '1', 19:00:02 INFO - 'TERM': 'linux', 19:00:02 INFO - 'TMOUT': '86400', 19:00:02 INFO - 'USER': 'cltbld', 19:00:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 19:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:02 INFO - '_': '/tools/buildbot/bin/python'} 19:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:02 INFO - Running setup.py (path:/tmp/pip-QGuWSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:02 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)) 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:02 INFO - Running setup.py (path:/tmp/pip-QwsZ3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:02 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)) 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:02 INFO - Running setup.py (path:/tmp/pip-KibW8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:02 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)) 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:02 INFO - Running setup.py (path:/tmp/pip-szi4mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:02 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)) 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:02 INFO - Running setup.py (path:/tmp/pip-xeMJ9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:02 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)) 19:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:02 INFO - Running setup.py (path:/tmp/pip-R4mhps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:03 INFO - Running setup.py (path:/tmp/pip-nKUTQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:03 INFO - Running setup.py (path:/tmp/pip-RPJiJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:03 INFO - Running setup.py (path:/tmp/pip-JU50g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:03 INFO - Running setup.py (path:/tmp/pip-TGXW3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:03 INFO - Running setup.py (path:/tmp/pip-9dN9wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:03 INFO - Running setup.py (path:/tmp/pip-DsgPjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:03 INFO - Running setup.py (path:/tmp/pip-BCczWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:03 INFO - Running setup.py (path:/tmp/pip-EuuY_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:03 INFO - Running setup.py (path:/tmp/pip-SZjvpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:03 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)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:03 INFO - Running setup.py (path:/tmp/pip-7vOBaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:03 INFO - Running setup.py (path:/tmp/pip-1To8Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:03 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)) 19:00:03 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)) 19:00:03 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)) 19:00:03 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)) 19:00:03 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)) 19:00:03 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)) 19:00:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:03 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 19:00:05 INFO - Downloading blessings-1.6.tar.gz 19:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:05 INFO - Installing collected packages: blessings 19:00:05 INFO - Running setup.py install for blessings 19:00:06 INFO - Successfully installed blessings 19:00:06 INFO - Cleaning up... 19:00:06 INFO - Return code: 0 19:00:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:06 INFO - Reading from file tmpfile_stdout 19:00:06 INFO - Current package versions: 19:00:06 INFO - argparse == 1.2.1 19:00:06 INFO - blessings == 1.6 19:00:06 INFO - blobuploader == 1.2.4 19:00:06 INFO - docopt == 0.6.1 19:00:06 INFO - functools32 == 3.2.3-2 19:00:06 INFO - jsonschema == 2.5.1 19:00:06 INFO - manifestparser == 1.1 19:00:06 INFO - mozInstall == 1.12 19:00:06 INFO - mozcrash == 1.0 19:00:06 INFO - mozdebug == 0.1 19:00:06 INFO - mozdevice == 0.49 19:00:06 INFO - mozfile == 1.2 19:00:06 INFO - mozhttpd == 0.7 19:00:06 INFO - mozinfo == 0.9 19:00:06 INFO - mozleak == 0.1 19:00:06 INFO - mozlog == 3.4 19:00:06 INFO - moznetwork == 0.27 19:00:06 INFO - mozprocess == 0.23 19:00:06 INFO - mozprofile == 0.28 19:00:06 INFO - mozrunner == 6.13 19:00:06 INFO - mozscreenshot == 0.1 19:00:06 INFO - mozsystemmonitor == 0.3 19:00:06 INFO - moztest == 0.8 19:00:06 INFO - mozversion == 1.4 19:00:06 INFO - psutil == 3.1.1 19:00:06 INFO - requests == 1.2.3 19:00:06 INFO - wsgiref == 0.1.2 19:00:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:06 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')]} 19:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:06 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')]} 19:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:06 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', '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 19:00:06 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 19:00:06 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 19:00:06 INFO - Using env: {'DISPLAY': ':0', 19:00:06 INFO - 'HOME': '/home/cltbld', 19:00:06 INFO - 'LANG': 'en_US.UTF-8', 19:00:06 INFO - 'LANGUAGE': 'en_US:en', 19:00:06 INFO - 'LOGNAME': 'cltbld', 19:00:06 INFO - 'MAIL': '/var/mail/cltbld', 19:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:06 INFO - 'MOZ_NO_REMOTE': '1', 19:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:06 INFO - 'NO_EM_RESTART': '1', 19:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:06 INFO - 'PWD': '/builds/slave/test', 19:00:06 INFO - 'SHELL': '/bin/bash', 19:00:06 INFO - 'SHLVL': '1', 19:00:06 INFO - 'TERM': 'linux', 19:00:06 INFO - 'TMOUT': '86400', 19:00:06 INFO - 'USER': 'cltbld', 19:00:06 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 19:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:06 INFO - '_': '/tools/buildbot/bin/python'} 19:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 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)) 19:00:06 INFO - Cleaning up... 19:00:06 INFO - Return code: 0 19:00:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:06 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')]} 19:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:06 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')]} 19:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:06 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 0x14ab1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x181d480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, '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': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', '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 19:00:06 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 19:00:06 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 19:00:06 INFO - Using env: {'DISPLAY': ':0', 19:00:06 INFO - 'HOME': '/home/cltbld', 19:00:06 INFO - 'LANG': 'en_US.UTF-8', 19:00:06 INFO - 'LANGUAGE': 'en_US:en', 19:00:06 INFO - 'LOGNAME': 'cltbld', 19:00:06 INFO - 'MAIL': '/var/mail/cltbld', 19:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:06 INFO - 'MOZ_NO_REMOTE': '1', 19:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:06 INFO - 'NO_EM_RESTART': '1', 19:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:06 INFO - 'PWD': '/builds/slave/test', 19:00:06 INFO - 'SHELL': '/bin/bash', 19:00:06 INFO - 'SHLVL': '1', 19:00:06 INFO - 'TERM': 'linux', 19:00:06 INFO - 'TMOUT': '86400', 19:00:06 INFO - 'USER': 'cltbld', 19:00:06 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 19:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:06 INFO - '_': '/tools/buildbot/bin/python'} 19:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:06 INFO - Cleaning up... 19:00:06 INFO - Return code: 0 19:00:06 INFO - Running post-action listener: _resource_record_post_action 19:00:06 INFO - Running post-action listener: _start_resource_monitoring 19:00:06 INFO - Starting resource monitoring. 19:00:06 INFO - [mozharness: 2017-01-06 03:00:06.808985Z] Finished create-virtualenv step (success) 19:00:06 INFO - [mozharness: 2017-01-06 03:00:06.809498Z] Running install step. 19:00:06 INFO - Running pre-action listener: _resource_record_pre_action 19:00:06 INFO - Running main action method: install 19:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:07 INFO - Reading from file tmpfile_stdout 19:00:07 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:07 INFO - Reading from file tmpfile_stdout 19:00:07 INFO - Output received: 19:00:07 INFO - Usage: mozinstall [options] installer 19:00:07 INFO - Options: 19:00:07 INFO - -h, --help show this help message and exit 19:00:07 INFO - -d DEST, --destination=DEST 19:00:07 INFO - Directory to install application into. [default: 19:00:07 INFO - "/builds/slave/test"] 19:00:07 INFO - --app=APP Application being installed. [default: firefox] 19:00:07 INFO - mkdir: /builds/slave/test/build/application 19:00:07 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'] 19:00:07 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 19:00:24 INFO - Reading from file tmpfile_stdout 19:00:24 INFO - Output received: 19:00:24 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:24 INFO - Running post-action listener: _resource_record_post_action 19:00:24 INFO - [mozharness: 2017-01-06 03:00:24.729988Z] Finished install step (success) 19:00:24 INFO - [mozharness: 2017-01-06 03:00:24.730262Z] Running run-tests step. 19:00:24 INFO - Running pre-action listener: _resource_record_pre_action 19:00:24 INFO - Running main action method: run_tests 19:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:24 INFO - Python 2.7.3 19:00:24 INFO - Return code: 0 19:00:24 INFO - grabbing minidump binary from tooltool 19:00: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')]} 19:00:24 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 0x181a820>, '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 0x181b520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x181b9b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:24 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 19:00:24 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 19:00:24 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 19:00:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:24 INFO - Return code: 0 19:00:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:24 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:00:24 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:00:24 INFO - Using env: {'DISPLAY': ':0', 19:00:24 INFO - 'HOME': '/home/cltbld', 19:00:24 INFO - 'LANG': 'en_US.UTF-8', 19:00:24 INFO - 'LANGUAGE': 'en_US:en', 19:00:24 INFO - 'LOGNAME': 'cltbld', 19:00:24 INFO - 'MAIL': '/var/mail/cltbld', 19:00:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:24 INFO - 'MOZ_NO_REMOTE': '1', 19:00:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:24 INFO - 'NO_EM_RESTART': '1', 19:00:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:24 INFO - 'PWD': '/builds/slave/test', 19:00:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:24 INFO - 'SHELL': '/bin/bash', 19:00:24 INFO - 'SHLVL': '1', 19:00:24 INFO - 'TERM': 'linux', 19:00:24 INFO - 'TMOUT': '86400', 19:00:24 INFO - 'USER': 'cltbld', 19:00:24 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798', 19:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:24 INFO - '_': '/tools/buildbot/bin/python'} 19:00:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:00:25 INFO - mozversion application_buildid: 20170105153437 19:00:25 INFO - mozversion application_changeset: 5e63be85c717efb6b5bfd2c4695bbba6d4947344 19:00:25 INFO - mozversion application_display_name: Nightly 19:00:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:25 INFO - mozversion application_name: Firefox 19:00:25 INFO - mozversion application_remotingname: firefox 19:00:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:00:25 INFO - mozversion application_vendor: Mozilla 19:00:25 INFO - mozversion application_version: 53.0a1 19:00:25 INFO - mozversion platform_buildid: 20170105153437 19:00:25 INFO - mozversion platform_changeset: 5e63be85c717efb6b5bfd2c4695bbba6d4947344 19:00:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:00:25 INFO - mozversion platform_version: 53.0a1 19:00:25 INFO - using testdate: 1483671625 19:00:25 INFO - actual date: 1483671625 19:00:25 INFO - starting webserver on 'localhost:39928' 19:00:25 INFO - SUITE-START | Running 2 tests 19:00:25 INFO - TEST-START | dromaeo_css 19:00:25 INFO - operating with platform_type : linux_ 19:00:25 INFO - Initialising browser for dromaeo_css test... 19:00:25 INFO - TEST-INFO | started process 32723 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeyjvk7/profile http://localhost:39928/getInfo.html) 19:00:25 INFO - PROCESS | 32723 | 1483671625464 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 19:00:28 INFO - PROCESS | 32723 | __metrics Screen width/height:1600/1200 19:00:28 INFO - PROCESS | 32723 | colorDepth:24 19:00:28 INFO - PROCESS | 32723 | Browser inner width/height: 1024/697 19:00:28 INFO - PROCESS | 32723 | __metrics 19:00:29 ERROR - PROCESS | 32723 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 19:00:30 INFO - TEST-INFO | 32723: exit 0 19:00:30 INFO - Browser initialized. 19:00:30 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:30 INFO - TEST-INFO | started process 376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeyjvk7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:42 INFO - PROCESS | 376 | RSS: Main: 154890240 19:00:42 INFO - PROCESS | 376 | 19:01:31 INFO - PROCESS | 376 | 1483671691811 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:03:42 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39928/tests/dromaeo/cssquery-ext.html) 19:03:43 INFO - PROCESS | 376 | RSS: Main: 162975744 19:03:43 INFO - PROCESS | 376 | 19:05:30 INFO - PROCESS | 376 | ************************* 19:05:30 INFO - PROCESS | 376 | A coding exception was thrown and uncaught in a Task. 19:05:30 INFO - PROCESS | 376 | 19:05:30 INFO - PROCESS | 376 | Full message: ReferenceError: fetch is not defined 19:05:30 INFO - PROCESS | 376 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:05:30 INFO - PROCESS | 376 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:05:30 INFO - PROCESS | 376 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:05:30 INFO - PROCESS | 376 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:05:30 INFO - PROCESS | 376 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:05:30 INFO - PROCESS | 376 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:05:30 INFO - PROCESS | 376 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:05:30 INFO - PROCESS | 376 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:05:30 INFO - PROCESS | 376 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:05:30 INFO - PROCESS | 376 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:05:30 INFO - PROCESS | 376 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:05:30 INFO - PROCESS | 376 | 19:05:30 INFO - PROCESS | 376 | ************************* 19:06:46 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-ext.html (next: http://localhost:39928/tests/dromaeo/cssquery-jquery.html) 19:06:46 INFO - PROCESS | 376 | RSS: Main: 160108544 19:06:46 INFO - PROCESS | 376 | 19:09:48 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39928/tests/dromaeo/cssquery-mootools.html) 19:09:49 INFO - PROCESS | 376 | RSS: Main: 165236736 19:09:49 INFO - PROCESS | 376 | 19:12:51 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39928/tests/dromaeo/cssquery-prototype.html) 19:12:51 INFO - PROCESS | 376 | RSS: Main: 161026048 19:12:51 INFO - PROCESS | 376 | 19:15:54 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39928/tests/dromaeo/cssquery-yui.html) 19:15:55 INFO - PROCESS | 376 | RSS: Main: 164474880 19:15:55 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | Cycle 1(1): loaded http://localhost:39928/tests/dromaeo/cssquery-yui.html (next: http://localhost:39928/tests/dromaeo/cssquery-dojo.html) 19:18:57 INFO - PROCESS | 376 | RSS: Main: 163598336 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | __start_tp_report 19:18:57 INFO - PROCESS | 376 | _x_x_mozilla_page_load 19:18:57 INFO - PROCESS | 376 | _x_x_mozilla_page_load_details 19:18:57 INFO - PROCESS | 376 | |i|pagename|runs| 19:18:57 INFO - PROCESS | 376 | |0;dojo.html;3192.952105718414;3215.871365145396;3229;3234.3207926335467;3239.36832317698;5346.117890548055;5442.918356224654;5505.807296744615;5509.72451377431;5513.476219759126;5364.329458817648;5434.945650543494;5447.400785913546;5497.257870187524;5504.036793561131;4829.9275510867365;4862.902741945159;4921.581665558414;4975.751212439382;4986.825461108862;4721.527847215285;4914.582260507843;4966.453690094087;4974.676646018015;5000.699958002513;4754.072955773626;4793.233082706785;4918.360613120271;4943.184374578191;4975.651704380694;4761;4774.355462012619;4820.662553621248;4865.051314993578;4915.754212289371;4815.686980346301;4815.879603009953;4832.685875418087;4853.223484242504;4868.561829435333;5246.160614301732;5358.91961620576;5366;5389.326334208224;5416.187571864212;5692.686902220376;5707.543396528268;5778.24882765242;5799.536037117021;5851.888141253148;5834.066549352126;5844.503217226498;5870.236869207019;5878.794242201502;5893.292804863401;5436.103042997901;5436.456354364532;5437.048516509595;5438.537724293479;5439.891202176013;5828.6794226317115;5885.588008839341;5901.1148327750325;5918.852028699231;5943.524518038546;5694.857628559319;5706.058500347396;5717.285339332583;5746.856328591735;5799.594028417971;5772.105323674838;5813.854653633608;5840.970795145997;5852.882942341129;5859.1797148399255;5665.66006039639;5690.487856092972;5701.971490142523;5758.424157584208;5762.654240745569;5707.686077265707;5708.429157084342;5716.685582293014;5725.742341594639;5737.254156959569;4300.4194433751045;4309.978450107792;4310.181065597589;4310.590493903074;4340.435743353344;3894.766314021168;3914.4911161549385;3916.5691773904846;3934.5081864766903;3934.626210509997;5551.084071128219;5669.433056694297;5679.772809087673;5680.034394153004;5681.403452637496;5901.793437229677;5909.556783241274;5913.58604897653;5921.61509501881;5965.463108320185;5605.887882242332;5620.100783874561;5622.91565626516;5623.943760562342;5625.54995600351;4732.739699316502;4767.118083154558;4846.830360937351;4862.927056094161;4868.586170175504;6106.694665266719;6171.47542458896;6180.6291622502795;6183.938160618336;6184.350643182491;5949.910751338647;5950.642961422329;5963.4334738199805;5965.791197308074;5988.520918326611;5905;5956.523478121653;6062.151298818258;6207.689615519296;6211.937880621136;5689.943100568941;5809.883802324014;5834.212381328636;5848.239728835224;5856.238688970407;5839.562032847469;5883.970580147072;5895.528357731457;5895.64626122434;5907.763689452546;5522.558195344443;5557.833265002057;5738.483536481736;5746.3966283541295;5753;5717.685527296038;5743.59794814359;5748.770049197985;5756.366799652119;5846.707664616667;5751.338596061507;5761.337446193574;5781.768729250783;5787;5797.971010144923;5562.554995600423;5818.6217895836635;5841.415858414124;5852.239208902815;5852.590318677651;5389.029974604452;5537.473939975695;5548.3619383769765;5580.2466666999435;5647.15292706081 19:18:57 INFO - PROCESS | 376 | |1;ext.html;560.8317504748575;562.7102042448138;582.9766809327627;588.1207594646005;613.025289789235;46969.76515117424;49921.251181232314;50169;50229.497705022935;50248.497515024836;47183.76408117963;47403;47569.76215118928;47730;47883.76058119713;9016.72949811505;9180.403273787182;9186.448813071203;9196.310276729271;10297.897021029807;10194.490275486232;10482.737931551696;10525.947370263177;10582.470876456146;10586.629467968583;10643.52104155315;10669.519871605797;10682.519286632081;10698.946505267462;10742.624008159715;10626.78746425071;10643.893561064446;10663.253572249945;10684.305520141166;10691.091257243144;10497.265191436603;10553;10560.683179504551;10575.841362379571;10588.894111058868;41552.79223603863;41787.373189402184;41823;41848;41889.37165942513;4183.49798024239;4187.539370669255;4261.659067274611;4288.849890253858;4294.5490723473895;30611.23471913198;30758.077257682304;30895.2276193097;31013.689863101306;31326.21684457884;4234.428352172479;4245.448091748084;4267.4025636410925;4272.209641216385;4407.581279778416;341319;341492.9999999975;342496;342548;345140;56458.43541564614;56719;56745.43254567401;56796;56827;59705.40294596998;59988.50143732034;60136.69931650401;60380;60521;15980.360785568448;16544.6691066178;16556.42052528156;16589;16603.501894943172;11973.880261197452;12312.384380780924;12465.314407707663;12731.87268127307;12930.288834114026;18395.54011149732;18466.815331846778;18557.19577486612;18560.443186704422;18597.44207673772;7050.365467107882;7053.402764590096;7166.856662866713;7186.712531498786;7468.626568671545;948.7628092976756;954.4416516337881;957.8467445208734;971.0580736685404;971.5190980464715;31095.68904310973;33124;33133;33239.50140747891;33324.166895827795;1114.359243435028;1116.8715597706368;1118.2284223885492;1126.5606413498742;1151.6890439581266;17039.48881533556;17506;17506.64986700259;17580.296788128333;17756.28974840992;506988;510046;510344;510936;511469;65028;65236.67381663156;65371;65539.34460655428;65972;1417.4967886400386;1459.3432955170215;1465.7141857337717;1485.4800819713014;1494.0736743219275;29597.556036659036;32437.67562324441;32488.837555813014;32550.466420708064;32763.672363277015;55325;55401;55649.16526252191;55746.442535574126;55899.720501397234;303674;304821;305438;306016;307380;27652.58521122225;28141.546108279726;28450;28835.85582072076;29996.70003299939;44617;44867.55132448634;45168;45468;45875.77062114802;37288.627113728515;37713.81143094267;37906.241875162894;38376;39201.6079839198;36890.262194755414;36972;37824.054398640255;38148.80925595465;38970;37093.62906370902;37475.25049499049;38059.42910856284;38383.23233535258;38572.80713596414;31230;33599.83200083984;34228;34326.14184645404;34564;25618.743812561635;26023.609645854947;26144;26342.604860926716;26575.468490630465 19:18:57 INFO - PROCESS | 376 | |2;jquery.html;409.24290063382745;424.2088504938287;425.7530632233305;429.9161663475622;445.8105305245272;19420.611587768235;20394.89802550987;21246.15015399382;21357.35927922163;21359.145634174653;20229.59540809183;20906.372808815722;20973.595544935542;21288.254911078115;21585.136594536238;5464.562834973202;5698.715064246771;5721.141828725704;5736.512396446307;5747.080467125261;5473.261109750192;5565;5610.915836262459;5642.7742890284335;5666.405027472118;5618.91571626426;5652.773889044433;5654.858628534299;5658.858528536778;5661.348944871331;5457.617966742329;5562.221289019521;5606.523445507137;5616.747246373923;5658.858528536778;5483.013057649621;5489.862753431156;5490.066688662937;5500.312460942382;5504.2294078829;20410.489737756525;25265.873670631714;25389.238322850342;25882.741172588223;26156.169068165236;2668.866556672159;3032.150997720642;3146.622405311354;3194.6965038321323;3194.712475877182;10726.624568140156;11014.229003969725;11131.666050018512;11294.548218071268;11536.711582210428;2024.44327809852;2035.9694604580943;2036.3890832750117;2041.1427200575795;2055.794420557947;102556.48721756344;103598;105962;106027.46986265096;107335;12100;12292.508299667914;12505.93747031259;12523.56167534132;12523.56167534132;17310;17313.2209050593;17342.566435839206;17350.47948561545;17366.739498907526;8654.480731156025;8860.601272942733;8918.063603321687;8960;9025.909740902507;8209.302209312274;8223.794405139799;8264.421490495728;8430.283425908743;8499.150084991452;5150.356205474316;5229.424763276036;5244.527992480619;5314.202869569595;5417.864553386197;2115.175081718131;2131.3925531223836;2193.243331050785;2195.792314227169;2210.1159536185655;958.769895225141;964.2767924056957;967.4630580028012;972.2902281334665;977.6089564174245;19144.712829307573;20239;20867;21077.683834742493;21139.78860211409;1000.3347773730533;1005.7787286797039;1008.848672699101;1029.4183786160795;1031.6801791444677;10883.23817332779;11403.714907127389;11500.942495287638;11692.883071169179;11783.234089784137;464547;481513;621999;635596;636366;112125;116878;118271.40864295623;118916;119498;1081.561967403203;1092.3773449134076;1133.3539882267194;1139.1342579639534;1144.5421831267392;18399.448016559523;18621.627567448846;18781.530461738566;19061.332853350064;19276.036198190315;45259.773701131286;45675.31487027764;47145;47673.28490072583;48172;59362.40637593742;68131.31868681249;68797.3120268791;68940.6552967232;70511;8620.224179823846;8803.119688031145;8914.331425143011;8949.418287811272;9193.77015574616;9328.720138395858;14226.786598201243;14300.499482518062;14649.780253295996;15305.846941530888;12233.3271670059;12339.383030848601;12449.25304481734;12566.811497827357;12857.448726055873;11825.40872956366;12101.213421127599;12346.320952347707;12509.687257818627;12593.307368094476;12569.994400448126;12585.811212831632;12635.873641263837;12762;12917.483300667867;8972.910270897468;8983.101689830964;9085.409448385833;9183.311251656287;9313.767155821159;10548.367097974146;12203.755924881276;12304.692382690504;12481.582720251068;12779.680507987374 19:18:57 INFO - PROCESS | 376 | |3;mootools.html;1419.1485108934635;1468.8017117689112;1475.1001888847802;1477.2761346940003;1490.3889405343807;7616.65725042373;7867;7976.521408715481;8387.28708059815;8417.78955526112;8074.555899425529;8210.671573137068;8244.54654993975;8415.410921235502;8416.747497575067;2180.062573093573;2228.4651683595903;2230.475838178027;2238.1942500699734;2242.1703969531295;2358.9410264743346;2360.5160942006846;2378.583747844129;2396.0655344415604;2398.6042303020067;2285.908563657452;2287.63397856343;2330.2892617751577;2332.1720789119872;2336.8481048731865;2244.887755612213;2254.1434254983083;2281.9201327953524;2282.303897311336;2290.4617414907484;2320.153144102407;2359.3393849722106;2360.5750964826325;2360.8229382796358;2365.31405892291;8392.916070839274;8470.110638382943;8555.10171431997;8557.871631925464;8585.613647385882;5184.948150518484;5333.786648534054;5337.2527846101575;5338.706371149585;5344.732763361855;3238.4170849247125;3239;3519.40170171069;3520.718342532617;3541.592716837545;1302.2186687987228;1319.1755153029358;1344.206917918423;1375.690469644332;1376.6764810269578;11371.464852245006;11407.31556106628;11434.599789007345;11444.885551144465;11505.942470287595;4383.60547550722;4669.929951050737;4697.365855609517;4724.622030237572;4796.856094317176;9778;9790.265730070274;9847.704568862946;9847.80304393908;9866.457344846101;5394;5398.24424580559;5406.675599463966;5434.239206511092;5437.102878025161;4266.146770645883;4784.330193772875;4878.292647566154;4924.507549245119;4971.1797553403285;3962.227365663668;4000.8599699010397;4050.9594904051173;4098.4876890388705;4121.2993791055;1370.9588712338646;1371.3417559571462;1372.444160115155;1386.2999185411504;1389.805427240187;977.3451787302382;977.5258999385293;981.1513041219293;1011.4437059617328;1027.0807627173638;5668.744906479218;5695.829125126253;5738.426157384228;5739.655620662774;5825.766969321265;629.3234772619452;634.2072409488139;645.7062036773257;650.8633187030777;655.4395991427357;3767.340715374853;3789.052736815796;3798.4302354647025;3881.902952426212;3914.7455415398476;9261.814763704688;9727.41635501858;9943.453110079015;10089.899101009043;10127.544260508148;10024.649137280161;10034.548445319979;10035.949820250851;10053.346532475365;10065.043820837009;7737.961310193413;7854.332381747501;7898.9210107899335;7958.761237162895;7959.562224077616;5464.672119672832;5651.802186923603;5678.176664383559;5687.9431205687415;5690.715464226855;8997.55012249398;9026.593803279;9044.502552359683;9078.863817042618;9122.498262595622;8881.42270752399;8986.55067246622;9003.954980225057;9102.089791020844;9141.3601047926;6764.661766911733;7000.299970003162;7160.928390716234;7343.889841652486;7460.850782984418;7895.921040789519;8149.511029338259;8169.142240064708;8185.672573097009;8231.588420579066;7298.890516642361;7337.70649174027;7463.2163622818825;7596.658150383357;7605.619719014137;7024.4029257513685;7105.538140020882;7116.786496405116;7284.089488813898;7325.926740732524;6325.778597749057;6427.453666438218;6436.195475565554;6437.098806167047;6446.8388290293115;4813.5186481352985;4883.755812209446;4894.339264199288;4896.804127834989;4979.825706100269;4746.92879606813;4751.5270266217585;4785.832495862628;4830.903381932412;4854.90290194187 19:18:57 INFO - PROCESS | 376 | |4;prototype.html;390.5040598439981;396.7143656567272;398.59343469660956;406.59137566745414;408.2956899348622;5279.63042587019;5442.15646574781;5455.890882182354;5549.556035517159;5553.833384998451;5302.946970530294;5403.973245083432;5457.2359869618185;5464.754086066127;5475.808346707866;4289.849855255059;4307.870763877088;4309.439772829528;4312.676549258803;4350.934735978963;3985.2428038672706;4162.125953549758;4305.526392096866;4347;4361.3676016977515;4081.632653061224;4111.177764447108;4136.131412403384;4142.212979533894;4143.854965076223;4116.238495878257;4119.786566478152;4129.525104612963;4164.1879833432495;4185.748855068675;3656.6526180012856;3664.3404187246283;3679.2641471705765;3680.429533422324;3691.7969511676847;5170.560502357304;5271.655727789427;5280.260763493114;5301.0723123453245;5327.668082979255;4781.784819683123;4808.831690890802;4820.349252850855;4824.710517368969;4833.637477189225;5599.412061733541;5734.913976290359;5753.453421924911;5754.654720716729;5771.182077645528;3995.5005624296964;4027.469561566615;4034.253663072341;4036.858709945167;4037.212743515016;5883.1760754837915;5883.852903677357;5930.805601927272;5949.345571987078;5961.761529538813;4136.5522067276215;4148.730332528376;4157.563455837154;4160.084781348099;4199.202151591188;4078.2455245779033;4230.1539692061715;4237.491501019897;4254.170436764801;4259.574042595777;4793.712377257306;4828.468868424541;4839.830605928775;4866.7079975201605;4896.314515967767;5682.857928551819;5706.71466426677;5707.514861236758;5729.5702822288495;5763.682997435181;1359.327133069137;1397.3153154954202;1418.971620567583;1426.7658475418689;1428.6999730056596;942.1944237676688;971.4747845882039;973.5570315506427;988.0860204310984;991.8742227571759;5224.320838290998;5237.292965449692;5250.947490525123;5263.421023687391;5331.840044798585;5650.434956504317;5703.287089113861;5720.513756330675;5768.307803063659;5784.479396854219;5248.921266181011;5370.9731451342495;5402.297701298885;5410.594205434608;5439.700816455133;3051.099925521932;3056.465118604235;3058.98470507646;3062.402831447891;3081.3221091359865;5827.679477628772;6006.579539432198;6019.291318906509;6040.637561746309;6041.516678665696;5767.991246084015;5769.086611261287;5777.942220577825;5808.680522571215;5862.746175872347;5861.267341582387;5901.498372638288;5964.761409543657;5970.253718285215;5972.820815375546;5525.778968841362;5706.034306157181;5731.19763233156;5825.621334613236;5888.646681199141;5796.884062318646;5805.3614102447855;5809.360970293349;5833.504152147115;5839.737211825393;5834.241548598655;6115.602485838406;6136.32500424944;6138.785142519991;6160.045192995093;4065.10567675111;4168.353905144708;4168.749875007509;4181.560936101666;4231.936520952249;5879.20630714848;5882.617629854046;5888.941110588839;5897.3807750187325;5902.645841249367;5731.226284451713;5758.510526605285;5765.164051212634;5773;5784.161296611884;5507.531859791962;5667.2349232854685;5699.772009119589;5715.628484148517;5720.399358067343;5677.148427735872;5692.715364231854;5707.200991861226;5717.1995920570325;5746.798862039808;5468.562514998711;5692.150051233283;5757.453041961007;5771.422857714195;5783.132530120515;5538.83684426275;5600.85997850057;5609.747561359667;5612.298462692163;5663.631863928831 19:18:57 INFO - PROCESS | 376 | |5;yui.html;738.6380673470002;743.8586668532978;773.37743116791;845.1928408370007;863.4603372891941;15656.452024179156;16065.678686426265;16760.16199190042;16767.83232167681;17148.971061736305;17853.91073044633;17961.371352002683;17973.37093201738;18128;18185.36351227704;746.1046743907306;758.9089309282894;771.3790398729021;773.0993392697529;773.5706682791049;760.8326168243005;764.0411283838774;769.2576663519702;769.3614300130878;769.3806485778946;817.7383237364046;818.3207937411963;819.692615269274;820.8973878265217;822.0505316359614;811.926926576608;815.1440986963694;816.9019717633862;819.1399031017468;819.5820131732829;597.0685730260807;599.0953659973428;599.9310079340888;599.9400059994009;601.1163589523437;14453.277336133151;14484.34820433083;14495;15141.091534507965;15663.530094097196;15325.770113448307;15516.922415387851;15767.605809854846;15914.840851591567;15948.362065517482;12705.17416367933;12822.42299096543;12899.613011609667;12906.80639790404;13556.796648050287;3821.178446633959;3824.388097904322;3854.9421758673643;3893.143508428141;4021.195760847842;81001;81961;82610.58694706549;82624;82837.58581207116;11729.706757330965;11767.470463829148;11893;11924.821127683092;11977.64067077989;23408.18071367528;23411.297661070195;23601.7639823603;23619.7638023621;23671.408214794425;8044.798880028046;8067.260434598115;8083.434159608771;8145.307648849915;8198.42611017235;4373.825046998148;4426.402435671208;4472.575105364985;4495.213337665896;4536.070105628338;3729.21686445844;3743.419769935664;3779.64093411131;3804.144067584771;3840.5007349044445;639.0861068671751;641.7368878759686;656.0487293424553;659.9736010559524;665.7869481765886;800.6917336825364;818.5660265440841;836.636063312461;843.7131375332416;866.8006358537572;10282.640107596199;10418.99218625418;10453.372797632015;10911.781764364827;11389.373584452935;789.9012623422072;791.1890312429806;798.7923139983646;804.94365394423;809.7246936041656;5343.893122137535;5349.491798279158;5477.424870388551;5516.724163791794;5533.944660553423;64514;76666.23333766703;79579;80610;81396.59301703454;58333.708331458074;58450.123248151314;66175.66912165408;66559.33440665629;68332;59961.70019149876;60135.398646013855;60327.69836150879;60676.393236067954;60977;10927.672169834917;11196;11279.436028198721;11460;11470.827937580776;41017;41845.58154418417;41906;42261.15477690368;42626;51791.22313165381;52656;52786.082087685616;53175.46824531705;54113.458865410845;13591.86408135906;13833.584992450242;13874;13876.306184690928;13934.721305573628;14885.627859303604;15164.18620320642;15199.77200342018;15272;15291.89796817823;12948.805767913676;13049.34753262352;13248.20510769357;13397.33013349348;13952.093113947967;13938.945034623632;14081.155130692192;14098.12647656732;14110.717785644434;14280.928595356956;14180.929095354457;14241.715165696834;14632.414703411745;14659.633509162357;15114.244287785346;11122.277051991594;11153.49809258569;11172.273802202832;11370.147238956946;11729.765404691687;11395.658130256106;11428.714282143013;11475;11498.916134677684;11597.362145082101 19:18:57 INFO - PROCESS | 376 | __end_tp_report 19:18:57 INFO - PROCESS | 376 | __start_cc_report 19:18:57 INFO - PROCESS | 376 | _x_x_mozilla_cycle_collect,335 19:18:57 INFO - PROCESS | 376 | __end_cc_report 19:18:57 INFO - PROCESS | 376 | __startTimestamp1483672737960__endTimestamp 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | ------- Summary: start ------- 19:18:57 INFO - PROCESS | 376 | Number of tests: 6 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#0] dojo.html Cycles:165 Average:5408.60 Median:5680.03 stddev:646.14 (11.4%) stddev-sans-first:624.31 19:18:57 INFO - PROCESS | 376 | Values: 3193.0 3215.9 3229.0 3234.3 3239.4 5346.1 5442.9 5505.8 5509.7 5513.5 5364.3 5434.9 5447.4 5497.3 5504.0 4829.9 4862.9 4921.6 4975.8 4986.8 4721.5 4914.6 4966.5 4974.7 5000.7 4754.1 4793.2 4918.4 4943.2 4975.7 4761.0 4774.4 4820.7 4865.1 4915.8 4815.7 4815.9 4832.7 4853.2 4868.6 5246.2 5358.9 5366.0 5389.3 5416.2 5692.7 5707.5 5778.2 5799.5 5851.9 5834.1 5844.5 5870.2 5878.8 5893.3 5436.1 5436.5 5437.0 5438.5 5439.9 5828.7 5885.6 5901.1 5918.9 5943.5 5694.9 5706.1 5717.3 5746.9 5799.6 5772.1 5813.9 5841.0 5852.9 5859.2 5665.7 5690.5 5702.0 5758.4 5762.7 5707.7 5708.4 5716.7 5725.7 5737.3 4300.4 4310.0 4310.2 4310.6 4340.4 3894.8 3914.5 3916.6 3934.5 3934.6 5551.1 5669.4 5679.8 5680.0 5681.4 5901.8 5909.6 5913.6 5921.6 5965.5 5605.9 5620.1 5622.9 5623.9 5625.5 4732.7 4767.1 4846.8 4862.9 4868.6 6106.7 6171.5 6180.6 6183.9 6184.4 5949.9 5950.6 5963.4 5965.8 5988.5 5905.0 5956.5 6062.2 6207.7 6211.9 5689.9 5809.9 5834.2 5848.2 5856.2 5839.6 5884.0 5895.5 5895.6 5907.8 5522.6 5557.8 5738.5 5746.4 5753.0 5717.7 5743.6 5748.8 5756.4 5846.7 5751.3 5761.3 5781.8 5787.0 5798.0 5562.6 5818.6 5841.4 5852.2 5852.6 5389.0 5537.5 5548.4 5580.2 5647.2 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#1] ext.html Cycles:180 Average:55952.11 Median:35727.13 stddev:104702.36 (293.1%) stddev-sans-first:104913.48 19:18:57 INFO - PROCESS | 376 | Values: 560.8 562.7 583.0 588.1 613.0 46969.8 49921.3 50169.0 50229.5 50248.5 47183.8 47403.0 47569.8 47730.0 47883.8 9016.7 9180.4 9186.4 9196.3 10297.9 10194.5 10482.7 10525.9 10582.5 10586.6 10643.5 10669.5 10682.5 10698.9 10742.6 10626.8 10643.9 10663.3 10684.3 10691.1 10497.3 10553.0 10560.7 10575.8 10588.9 41552.8 41787.4 41823.0 41848.0 41889.4 4183.5 4187.5 4261.7 4288.8 4294.5 30611.2 30758.1 30895.2 31013.7 31326.2 4234.4 4245.4 4267.4 4272.2 4407.6 341319.0 341493.0 342496.0 342548.0 345140.0 56458.4 56719.0 56745.4 56796.0 56827.0 59705.4 59988.5 60136.7 60380.0 60521.0 15980.4 16544.7 16556.4 16589.0 16603.5 11973.9 12312.4 12465.3 12731.9 12930.3 18395.5 18466.8 18557.2 18560.4 18597.4 7050.4 7053.4 7166.9 7186.7 7468.6 948.8 954.4 957.8 971.1 971.5 31095.7 33124.0 33133.0 33239.5 33324.2 1114.4 1116.9 1118.2 1126.6 1151.7 17039.5 17506.0 17506.6 17580.3 17756.3 506988.0 510046.0 510344.0 510936.0 511469.0 65028.0 65236.7 65371.0 65539.3 65972.0 1417.5 1459.3 1465.7 1485.5 1494.1 29597.6 32437.7 32488.8 32550.5 32763.7 55325.0 55401.0 55649.2 55746.4 55899.7 303674.0 304821.0 305438.0 306016.0 307380.0 27652.6 28141.5 28450.0 28835.9 29996.7 44617.0 44867.6 45168.0 45468.0 45875.8 37288.6 37713.8 37906.2 38376.0 39201.6 36890.3 36972.0 37824.1 38148.8 38970.0 37093.6 37475.3 38059.4 38383.2 38572.8 31230.0 33599.8 34228.0 34326.1 34564.0 25618.7 26023.6 26144.0 26342.6 26575.5 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#2] jquery.html Cycles:180 Average:33459.07 Median:21472.14 stddev:95158.35 (443.2%) stddev-sans-first:95392.94 19:18:57 INFO - PROCESS | 376 | Values: 409.2 424.2 425.8 429.9 445.8 19420.6 20394.9 21246.2 21357.4 21359.1 20229.6 20906.4 20973.6 21288.3 21585.1 5464.6 5698.7 5721.1 5736.5 5747.1 5473.3 5565.0 5610.9 5642.8 5666.4 5618.9 5652.8 5654.9 5658.9 5661.3 5457.6 5562.2 5606.5 5616.7 5658.9 5483.0 5489.9 5490.1 5500.3 5504.2 20410.5 25265.9 25389.2 25882.7 26156.2 2668.9 3032.2 3146.6 3194.7 3194.7 10726.6 11014.2 11131.7 11294.5 11536.7 2024.4 2036.0 2036.4 2041.1 2055.8 102556.5 103598.0 105962.0 106027.5 107335.0 12100.0 12292.5 12505.9 12523.6 12523.6 17310.0 17313.2 17342.6 17350.5 17366.7 8654.5 8860.6 8918.1 8960.0 9025.9 8209.3 8223.8 8264.4 8430.3 8499.2 5150.4 5229.4 5244.5 5314.2 5417.9 2115.2 2131.4 2193.2 2195.8 2210.1 958.8 964.3 967.5 972.3 977.6 19144.7 20239.0 20867.0 21077.7 21139.8 1000.3 1005.8 1008.8 1029.4 1031.7 10883.2 11403.7 11500.9 11692.9 11783.2 464547.0 481513.0 621999.0 635596.0 636366.0 112125.0 116878.0 118271.4 118916.0 119498.0 1081.6 1092.4 1133.4 1139.1 1144.5 18399.4 18621.6 18781.5 19061.3 19276.0 45259.8 45675.3 47145.0 47673.3 48172.0 59362.4 68131.3 68797.3 68940.7 70511.0 8620.2 8803.1 8914.3 8949.4 9193.8 9328.7 14226.8 14300.5 14649.8 15305.8 12233.3 12339.4 12449.3 12566.8 12857.4 11825.4 12101.2 12346.3 12509.7 12593.3 12570.0 12585.8 12635.9 12762.0 12917.5 8972.9 8983.1 9085.4 9183.3 9313.8 10548.4 12203.8 12304.7 12481.6 12779.7 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#3] mootools.html Cycles:180 Average:5502.79 Median:5335.52 stddev:2997.81 (56.2%) stddev-sans-first:2990.51 19:18:57 INFO - PROCESS | 376 | Values: 1419.1 1468.8 1475.1 1477.3 1490.4 7616.7 7867.0 7976.5 8387.3 8417.8 8074.6 8210.7 8244.5 8415.4 8416.7 2180.1 2228.5 2230.5 2238.2 2242.2 2358.9 2360.5 2378.6 2396.1 2398.6 2285.9 2287.6 2330.3 2332.2 2336.8 2244.9 2254.1 2281.9 2282.3 2290.5 2320.2 2359.3 2360.6 2360.8 2365.3 8392.9 8470.1 8555.1 8557.9 8585.6 5184.9 5333.8 5337.3 5338.7 5344.7 3238.4 3239.0 3519.4 3520.7 3541.6 1302.2 1319.2 1344.2 1375.7 1376.7 11371.5 11407.3 11434.6 11444.9 11505.9 4383.6 4669.9 4697.4 4724.6 4796.9 9778.0 9790.3 9847.7 9847.8 9866.5 5394.0 5398.2 5406.7 5434.2 5437.1 4266.1 4784.3 4878.3 4924.5 4971.2 3962.2 4000.9 4051.0 4098.5 4121.3 1371.0 1371.3 1372.4 1386.3 1389.8 977.3 977.5 981.2 1011.4 1027.1 5668.7 5695.8 5738.4 5739.7 5825.8 629.3 634.2 645.7 650.9 655.4 3767.3 3789.1 3798.4 3881.9 3914.7 9261.8 9727.4 9943.5 10089.9 10127.5 10024.6 10034.5 10035.9 10053.3 10065.0 7738.0 7854.3 7898.9 7958.8 7959.6 5464.7 5651.8 5678.2 5687.9 5690.7 8997.6 9026.6 9044.5 9078.9 9122.5 8881.4 8986.6 9004.0 9102.1 9141.4 6764.7 7000.3 7160.9 7343.9 7460.9 7895.9 8149.5 8169.1 8185.7 8231.6 7298.9 7337.7 7463.2 7596.7 7605.6 7024.4 7105.5 7116.8 7284.1 7325.9 6325.8 6427.5 6436.2 6437.1 6446.8 4813.5 4883.8 4894.3 4896.8 4979.8 4746.9 4751.5 4785.8 4830.9 4854.9 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#4] prototype.html Cycles:180 Average:4780.32 Median:5461.00 stddev:1407.08 (25.8%) stddev-sans-first:1371.91 19:18:57 INFO - PROCESS | 376 | Values: 390.5 396.7 398.6 406.6 408.3 5279.6 5442.2 5455.9 5549.6 5553.8 5302.9 5404.0 5457.2 5464.8 5475.8 4289.8 4307.9 4309.4 4312.7 4350.9 3985.2 4162.1 4305.5 4347.0 4361.4 4081.6 4111.2 4136.1 4142.2 4143.9 4116.2 4119.8 4129.5 4164.2 4185.7 3656.7 3664.3 3679.3 3680.4 3691.8 5170.6 5271.7 5280.3 5301.1 5327.7 4781.8 4808.8 4820.3 4824.7 4833.6 5599.4 5734.9 5753.5 5754.7 5771.2 3995.5 4027.5 4034.3 4036.9 4037.2 5883.2 5883.9 5930.8 5949.3 5961.8 4136.6 4148.7 4157.6 4160.1 4199.2 4078.2 4230.2 4237.5 4254.2 4259.6 4793.7 4828.5 4839.8 4866.7 4896.3 5682.9 5706.7 5707.5 5729.6 5763.7 1359.3 1397.3 1419.0 1426.8 1428.7 942.2 971.5 973.6 988.1 991.9 5224.3 5237.3 5250.9 5263.4 5331.8 5650.4 5703.3 5720.5 5768.3 5784.5 5248.9 5371.0 5402.3 5410.6 5439.7 3051.1 3056.5 3059.0 3062.4 3081.3 5827.7 6006.6 6019.3 6040.6 6041.5 5768.0 5769.1 5777.9 5808.7 5862.7 5861.3 5901.5 5964.8 5970.3 5972.8 5525.8 5706.0 5731.2 5825.6 5888.6 5796.9 5805.4 5809.4 5833.5 5839.7 5834.2 6115.6 6136.3 6138.8 6160.0 4065.1 4168.4 4168.7 4181.6 4231.9 5879.2 5882.6 5888.9 5897.4 5902.6 5731.2 5758.5 5765.2 5773.0 5784.2 5507.5 5667.2 5699.8 5715.6 5720.4 5677.1 5692.7 5707.2 5717.2 5746.8 5468.6 5692.2 5757.5 5771.4 5783.1 5538.8 5600.9 5609.7 5612.3 5663.6 19:18:57 INFO - PROCESS | 376 | 19:18:57 INFO - PROCESS | 376 | [#5] yui.html Cycles:180 Average:17756.70 Median:41431.29 stddev:21895.12 (52.8%) stddev-sans-first:21919.24 19:18:57 INFO - PROCESS | 376 | Values: 738.6 743.9 773.4 845.2 863.5 15656.5 16065.7 16760.2 16767.8 17149.0 17853.9 17961.4 17973.4 18128.0 18185.4 746.1 758.9 771.4 773.1 773.6 760.8 764.0 769.3 769.4 769.4 817.7 818.3 819.7 820.9 822.1 811.9 815.1 816.9 819.1 819.6 597.1 599.1 599.9 599.9 601.1 14453.3 14484.3 14495.0 15141.1 15663.5 15325.8 15516.9 15767.6 15914.8 15948.4 12705.2 12822.4 12899.6 12906.8 13556.8 3821.2 3824.4 3854.9 3893.1 4021.2 81001.0 81961.0 82610.6 82624.0 82837.6 11729.7 11767.5 11893.0 11924.8 11977.6 23408.2 23411.3 23601.8 23619.8 23671.4 8044.8 8067.3 8083.4 8145.3 8198.4 4373.8 4426.4 4472.6 4495.2 4536.1 3729.2 3743.4 3779.6 3804.1 3840.5 639.1 641.7 656.0 660.0 665.8 800.7 818.6 836.6 843.7 866.8 10282.6 10419.0 10453.4 10911.8 11389.4 789.9 791.2 798.8 804.9 809.7 5343.9 5349.5 5477.4 5516.7 5533.9 64514.0 76666.2 79579.0 80610.0 81396.6 58333.7 58450.1 66175.7 66559.3 68332.0 59961.7 60135.4 60327.7 60676.4 60977.0 10927.7 11196.0 11279.4 11460.0 11470.8 41017.0 41845.6 41906.0 42261.2 42626.0 51791.2 52656.0 52786.1 53175.5 54113.5 13591.9 13833.6 13874.0 13876.3 13934.7 14885.6 15164.2 15199.8 15272.0 15291.9 12948.8 13049.3 13248.2 13397.3 13952.1 13938.9 14081.2 14098.1 14110.7 14280.9 14180.9 14241.7 14632.4 14659.6 15114.2 11122.3 11153.5 11172.3 11370.1 11729.8 11395.7 11428.7 11475.0 11498.9 11597.4 19:18:57 INFO - PROCESS | 376 | -------- Summary: end -------- 19:18:57 INFO - PROCESS | 376 | 19:18:58 INFO - TEST-INFO | 376: exit 0 19:18:58 INFO - TEST-OK | dromaeo_css | took 1113695ms 19:18:58 INFO - TEST-START | kraken 19:18:58 INFO - operating with platform_type : linux_ 19:18:58 INFO - Initialising browser for kraken test... 19:18:58 INFO - TEST-INFO | started process 733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8fQNMh/profile http://localhost:39928/getInfo.html) 19:18:59 INFO - PROCESS | 733 | 1483672739175 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 19:19:02 INFO - PROCESS | 733 | __metrics Screen width/height:1600/1200 19:19:02 INFO - PROCESS | 733 | colorDepth:24 19:19:02 INFO - PROCESS | 733 | Browser inner width/height: 1024/697 19:19:02 INFO - PROCESS | 733 | __metrics 19:19:02 ERROR - PROCESS | 733 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 19:19:03 INFO - TEST-INFO | 733: exit 0 19:19:03 INFO - Browser initialized. 19:19:03 INFO - Running cycle 1/1 for kraken test... 19:19:03 INFO - TEST-INFO | started process 859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8fQNMh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:15 INFO - PROCESS | 859 | RSS: Main: 155385856 19:19:15 INFO - PROCESS | 859 | 19:19:18 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39928/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:19 INFO - PROCESS | 859 | RSS: Main: 161275904 19:19:19 INFO - PROCESS | 859 | 19:19:21 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39928/tests/kraken/driver.html?testName=audio-dft) 19:19:22 INFO - PROCESS | 859 | RSS: Main: 159981568 19:19:22 INFO - PROCESS | 859 | 19:19:25 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39928/tests/kraken/driver.html?testName=audio-fft) 19:19:25 INFO - PROCESS | 859 | RSS: Main: 156467200 19:19:25 INFO - PROCESS | 859 | 19:19:28 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39928/tests/kraken/driver.html?testName=audio-oscillator) 19:19:28 INFO - PROCESS | 859 | RSS: Main: 157515776 19:19:28 INFO - PROCESS | 859 | 19:19:30 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39928/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:19:31 INFO - PROCESS | 859 | RSS: Main: 158068736 19:19:31 INFO - PROCESS | 859 | 19:19:34 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39928/tests/kraken/driver.html?testName=imaging-darkroom) 19:19:35 INFO - PROCESS | 859 | RSS: Main: 161775616 19:19:35 INFO - PROCESS | 859 | 19:19:38 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39928/tests/kraken/driver.html?testName=imaging-desaturate) 19:19:39 INFO - PROCESS | 859 | RSS: Main: 162000896 19:19:39 INFO - PROCESS | 859 | 19:19:41 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39928/tests/kraken/driver.html?testName=json-parse-financial) 19:19:42 INFO - PROCESS | 859 | RSS: Main: 161570816 19:19:42 INFO - PROCESS | 859 | 19:19:44 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39928/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:19:44 INFO - PROCESS | 859 | RSS: Main: 160722944 19:19:44 INFO - PROCESS | 859 | 19:19:46 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:19:47 INFO - PROCESS | 859 | RSS: Main: 160931840 19:19:47 INFO - PROCESS | 859 | 19:19:49 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:19:50 INFO - PROCESS | 859 | RSS: Main: 161484800 19:19:50 INFO - PROCESS | 859 | 19:19:52 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:19:53 INFO - PROCESS | 859 | RSS: Main: 161374208 19:19:53 INFO - PROCESS | 859 | 19:19:56 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:19:56 INFO - PROCESS | 859 | RSS: Main: 162021376 19:19:56 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | Cycle 1(1): loaded http://localhost:39928/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39928/tests/kraken/driver.html?testName=ai-astar) 19:19:58 INFO - PROCESS | 859 | RSS: Main: 163622912 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | __start_tp_report 19:19:58 INFO - PROCESS | 859 | _x_x_mozilla_page_load 19:19:58 INFO - PROCESS | 859 | _x_x_mozilla_page_load_details 19:19:58 INFO - PROCESS | 859 | |i|pagename|runs| 19:19:58 INFO - PROCESS | 859 | |0;ai-astar;104;114;111;113;113;116;112;114;113;111 19:19:58 INFO - PROCESS | 859 | |1;audio-beat-detection;143;122;118;159;124;116;118;117;139;121 19:19:58 INFO - PROCESS | 859 | |2;audio-dft;165;162;163;162;166;165;164;166;162;163 19:19:58 INFO - PROCESS | 859 | |3;audio-fft;75;76;75;111;77;76;76;76;100;77 19:19:58 INFO - PROCESS | 859 | |4;audio-oscillator;84;84;102;85;86;86;97;86;83;103 19:19:58 INFO - PROCESS | 859 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;118;119;119 19:19:58 INFO - PROCESS | 859 | |6;imaging-darkroom;148;148;147;147;147;148;148;147;148;147 19:19:58 INFO - PROCESS | 859 | |7;imaging-desaturate;96;96;96;96;96;96;96;98;96;95 19:19:58 INFO - PROCESS | 859 | |8;json-parse-financial;57;55;64;57;57;61;58;57;57;59 19:19:58 INFO - PROCESS | 859 | |9;json-stringify-tinderbox;69;68;68;69;73;68;69;69;68;68 19:19:58 INFO - PROCESS | 859 | |10;stanford-crypto-aes;81;83;90;84;87;82;94;82;81;84 19:19:58 INFO - PROCESS | 859 | |11;stanford-crypto-ccm;124;126;129;129;140;139;124;135;173;128 19:19:58 INFO - PROCESS | 859 | |12;stanford-crypto-pbkdf2;167;172;171;184;168;168;162;165;169;161 19:19:58 INFO - PROCESS | 859 | |13;stanford-crypto-sha256-iterative;64;65;63;67;64;63;63;62;66;62 19:19:58 INFO - PROCESS | 859 | __end_tp_report 19:19:58 INFO - PROCESS | 859 | __start_cc_report 19:19:58 INFO - PROCESS | 859 | _x_x_mozilla_cycle_collect,714 19:19:58 INFO - PROCESS | 859 | __end_cc_report 19:19:58 INFO - PROCESS | 859 | __startTimestamp1483672798797__endTimestamp 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | ------- Summary: start ------- 19:19:58 INFO - PROCESS | 859 | Number of tests: 14 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.21 (2.8%) stddev-sans-first:1.58 19:19:58 INFO - PROCESS | 859 | Values: 104.0 114.0 111.0 113.0 113.0 116.0 112.0 114.0 113.0 111.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:123.00 stddev:14.42 (11.7%) stddev-sans-first:14.20 19:19:58 INFO - PROCESS | 859 | Values: 143.0 122.0 118.0 159.0 124.0 116.0 118.0 117.0 139.0 121.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.50 stddev:1.62 (1.0%) stddev-sans-first:1.66 19:19:58 INFO - PROCESS | 859 | Values: 165.0 162.0 163.0 162.0 166.0 165.0 164.0 166.0 162.0 163.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:12.72 (16.7%) stddev-sans-first:13.25 19:19:58 INFO - PROCESS | 859 | Values: 75.0 76.0 75.0 111.0 77.0 76.0 76.0 76.0 100.0 77.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:85.50 stddev:7.85 (9.2%) stddev-sans-first:8.06 19:19:58 INFO - PROCESS | 859 | Values: 84.0 84.0 102.0 85.0 86.0 86.0 97.0 86.0 83.0 103.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:117.50 stddev:0.84 (0.7%) stddev-sans-first:0.87 19:19:58 INFO - PROCESS | 859 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 119.0 119.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 19:19:58 INFO - PROCESS | 859 | Values: 148.0 148.0 147.0 147.0 147.0 148.0 148.0 147.0 148.0 147.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 19:19:58 INFO - PROCESS | 859 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 98.0 96.0 95.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:57.50 stddev:2.57 (4.5%) stddev-sans-first:2.69 19:19:58 INFO - PROCESS | 859 | Values: 57.0 55.0 64.0 57.0 57.0 61.0 58.0 57.0 57.0 59.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:1.52 (2.2%) stddev-sans-first:1.62 19:19:58 INFO - PROCESS | 859 | Values: 69.0 68.0 68.0 69.0 73.0 68.0 69.0 69.0 68.0 68.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.00 stddev:4.29 (5.1%) stddev-sans-first:4.32 19:19:58 INFO - PROCESS | 859 | Values: 81.0 83.0 90.0 84.0 87.0 82.0 94.0 82.0 81.0 84.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#11] stanford-crypto-ccm Cycles:10 Average:134.70 Median:132.00 stddev:14.64 (11.1%) stddev-sans-first:15.00 19:19:58 INFO - PROCESS | 859 | Values: 124.0 126.0 129.0 129.0 140.0 139.0 124.0 135.0 173.0 128.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:168.50 stddev:6.43 (3.8%) stddev-sans-first:6.79 19:19:58 INFO - PROCESS | 859 | Values: 167.0 172.0 171.0 184.0 168.0 168.0 162.0 165.0 169.0 161.0 19:19:58 INFO - PROCESS | 859 | 19:19:58 INFO - PROCESS | 859 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:1.66 (2.6%) stddev-sans-first:1.76 19:19:58 INFO - PROCESS | 859 | Values: 64.0 65.0 63.0 67.0 64.0 63.0 63.0 62.0 66.0 62.0 19:19:58 INFO - PROCESS | 859 | -------- Summary: end -------- 19:19:58 INFO - PROCESS | 859 | 19:19:59 INFO - TEST-INFO | 859: exit 0 19:19:59 INFO - TEST-OK | kraken | took 61146ms 19:19:59 INFO - SUITE-END | took 1174s 19:19:59 INFO - Completed test suite (00:19:34) 19:19:59 INFO - javascript benchmark 19:20:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7348.519906184109, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3192.952105718414, 3215.871365145396, 3229.0, 3234.3207926335467, 3239.36832317698, 5346.117890548055, 5442.918356224654, 5505.807296744615, 5509.72451377431, 5513.476219759126, 5364.329458817648, 5434.945650543494, 5447.400785913546, 5497.257870187524, 5504.036793561131, 4829.9275510867365, 4862.902741945159, 4921.581665558414, 4975.751212439382, 4986.825461108862, 4721.527847215285, 4914.582260507843, 4966.453690094087, 4974.676646018015, 5000.699958002513, 4754.072955773626, 4793.233082706785, 4918.360613120271, 4943.184374578191, 4975.651704380694, 4761.0, 4774.355462012619, 4820.662553621248, 4865.051314993578, 4915.754212289371, 4815.686980346301, 4815.879603009953, 4832.685875418087, 4853.223484242504, 4868.561829435333, 5246.160614301732, 5358.91961620576, 5366.0, 5389.326334208224, 5416.187571864212, 5692.686902220376, 5707.543396528268, 5778.24882765242, 5799.536037117021, 5851.888141253148, 5834.066549352126, 5844.503217226498, 5870.236869207019, 5878.794242201502, 5893.292804863401, 5436.103042997901, 5436.456354364532, 5437.048516509595, 5438.537724293479, 5439.891202176013, 5828.6794226317115, 5885.588008839341, 5901.1148327750325, 5918.852028699231, 5943.524518038546, 5694.857628559319, 5706.058500347396, 5717.285339332583, 5746.856328591735, 5799.594028417971, 5772.105323674838, 5813.854653633608, 5840.970795145997, 5852.882942341129, 5859.1797148399255, 5665.66006039639, 5690.487856092972, 5701.971490142523, 5758.424157584208, 5762.654240745569, 5707.686077265707, 5708.429157084342, 5716.685582293014, 5725.742341594639, 5737.254156959569, 4300.4194433751045, 4309.978450107792, 4310.181065597589, 4310.590493903074, 4340.435743353344, 3894.766314021168, 3914.4911161549385, 3916.5691773904846, 3934.5081864766903, 3934.626210509997, 5551.084071128219, 5669.433056694297, 5679.772809087673, 5680.034394153004, 5681.403452637496, 5901.793437229677, 5909.556783241274, 5913.58604897653, 5921.61509501881, 5965.463108320185, 5605.887882242332, 5620.100783874561, 5622.91565626516, 5623.943760562342, 5625.54995600351, 4732.739699316502, 4767.118083154558, 4846.830360937351, 4862.927056094161, 4868.586170175504, 6106.694665266719, 6171.47542458896, 6180.6291622502795, 6183.938160618336, 6184.350643182491, 5949.910751338647, 5950.642961422329, 5963.4334738199805, 5965.791197308074, 5988.520918326611, 5905.0, 5956.523478121653, 6062.151298818258, 6207.689615519296, 6211.937880621136, 5689.943100568941, 5809.883802324014, 5834.212381328636, 5848.239728835224, 5856.238688970407, 5839.562032847469, 5883.970580147072, 5895.528357731457, 5895.64626122434, 5907.763689452546, 5522.558195344443, 5557.833265002057, 5738.483536481736, 5746.3966283541295, 5753.0, 5717.685527296038, 5743.59794814359, 5748.770049197985, 5756.366799652119, 5846.707664616667, 5751.338596061507, 5761.337446193574, 5781.768729250783, 5787.0, 5797.971010144923, 5562.554995600423, 5818.6217895836635, 5841.415858414124, 5852.239208902815, 5852.590318677651, 5389.029974604452, 5537.473939975695, 5548.3619383769765, 5580.2466666999435, 5647.15292706081], "value": 5363.724298265219, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.8317504748575, 562.7102042448138, 582.9766809327627, 588.1207594646005, 613.025289789235, 46969.76515117424, 49921.251181232314, 50169.0, 50229.497705022935, 50248.497515024836, 47183.76408117963, 47403.0, 47569.76215118928, 47730.0, 47883.76058119713, 9016.72949811505, 9180.403273787182, 9186.448813071203, 9196.310276729271, 10297.897021029807, 10194.490275486232, 10482.737931551696, 10525.947370263177, 10582.470876456146, 10586.629467968583, 10643.52104155315, 10669.519871605797, 10682.519286632081, 10698.946505267462, 10742.624008159715, 10626.78746425071, 10643.893561064446, 10663.253572249945, 10684.305520141166, 10691.091257243144, 10497.265191436603, 10553.0, 10560.683179504551, 10575.841362379571, 10588.894111058868, 41552.79223603863, 41787.373189402184, 41823.0, 41848.0, 41889.37165942513, 4183.49798024239, 4187.539370669255, 4261.659067274611, 4288.849890253858, 4294.5490723473895, 30611.23471913198, 30758.077257682304, 30895.2276193097, 31013.689863101306, 31326.21684457884, 4234.428352172479, 4245.448091748084, 4267.4025636410925, 4272.209641216385, 4407.581279778416, 341319.0, 341492.9999999975, 342496.0, 342548.0, 345140.0, 56458.43541564614, 56719.0, 56745.43254567401, 56796.0, 56827.0, 59705.40294596998, 59988.50143732034, 60136.69931650401, 60380.0, 60521.0, 15980.360785568448, 16544.6691066178, 16556.42052528156, 16589.0, 16603.501894943172, 11973.880261197452, 12312.384380780924, 12465.314407707663, 12731.87268127307, 12930.288834114026, 18395.54011149732, 18466.815331846778, 18557.19577486612, 18560.443186704422, 18597.44207673772, 7050.365467107882, 7053.402764590096, 7166.856662866713, 7186.712531498786, 7468.626568671545, 948.7628092976756, 954.4416516337881, 957.8467445208734, 971.0580736685404, 971.5190980464715, 31095.68904310973, 33124.0, 33133.0, 33239.50140747891, 33324.166895827795, 1114.359243435028, 1116.8715597706368, 1118.2284223885492, 1126.5606413498742, 1151.6890439581266, 17039.48881533556, 17506.0, 17506.64986700259, 17580.296788128333, 17756.28974840992, 506988.0, 510046.0, 510344.0, 510936.0, 511469.0, 65028.0, 65236.67381663156, 65371.0, 65539.34460655428, 65972.0, 1417.4967886400386, 1459.3432955170215, 1465.7141857337717, 1485.4800819713014, 1494.0736743219275, 29597.556036659036, 32437.67562324441, 32488.837555813014, 32550.466420708064, 32763.672363277015, 55325.0, 55401.0, 55649.16526252191, 55746.442535574126, 55899.720501397234, 303674.0, 304821.0, 305438.0, 306016.0, 307380.0, 27652.58521122225, 28141.546108279726, 28450.0, 28835.85582072076, 29996.70003299939, 44617.0, 44867.55132448634, 45168.0, 45468.0, 45875.77062114802, 37288.627113728515, 37713.81143094267, 37906.241875162894, 38376.0, 39201.6079839198, 36890.262194755414, 36972.0, 37824.054398640255, 38148.80925595465, 38970.0, 37093.62906370902, 37475.25049499049, 38059.42910856284, 38383.23233535258, 38572.80713596414, 31230.0, 33599.83200083984, 34228.0, 34326.14184645404, 34564.0, 25618.743812561635, 26023.609645854947, 26144.0, 26342.604860926716, 26575.468490630465], "value": 20330.890599603124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.24290063382745, 424.2088504938287, 425.7530632233305, 429.9161663475622, 445.8105305245272, 19420.611587768235, 20394.89802550987, 21246.15015399382, 21357.35927922163, 21359.145634174653, 20229.59540809183, 20906.372808815722, 20973.595544935542, 21288.254911078115, 21585.136594536238, 5464.562834973202, 5698.715064246771, 5721.141828725704, 5736.512396446307, 5747.080467125261, 5473.261109750192, 5565.0, 5610.915836262459, 5642.7742890284335, 5666.405027472118, 5618.91571626426, 5652.773889044433, 5654.858628534299, 5658.858528536778, 5661.348944871331, 5457.617966742329, 5562.221289019521, 5606.523445507137, 5616.747246373923, 5658.858528536778, 5483.013057649621, 5489.862753431156, 5490.066688662937, 5500.312460942382, 5504.2294078829, 20410.489737756525, 25265.873670631714, 25389.238322850342, 25882.741172588223, 26156.169068165236, 2668.866556672159, 3032.150997720642, 3146.622405311354, 3194.6965038321323, 3194.712475877182, 10726.624568140156, 11014.229003969725, 11131.666050018512, 11294.548218071268, 11536.711582210428, 2024.44327809852, 2035.9694604580943, 2036.3890832750117, 2041.1427200575795, 2055.794420557947, 102556.48721756344, 103598.0, 105962.0, 106027.46986265096, 107335.0, 12100.0, 12292.508299667914, 12505.93747031259, 12523.56167534132, 12523.56167534132, 17310.0, 17313.2209050593, 17342.566435839206, 17350.47948561545, 17366.739498907526, 8654.480731156025, 8860.601272942733, 8918.063603321687, 8960.0, 9025.909740902507, 8209.302209312274, 8223.794405139799, 8264.421490495728, 8430.283425908743, 8499.150084991452, 5150.356205474316, 5229.424763276036, 5244.527992480619, 5314.202869569595, 5417.864553386197, 2115.175081718131, 2131.3925531223836, 2193.243331050785, 2195.792314227169, 2210.1159536185655, 958.769895225141, 964.2767924056957, 967.4630580028012, 972.2902281334665, 977.6089564174245, 19144.712829307573, 20239.0, 20867.0, 21077.683834742493, 21139.78860211409, 1000.3347773730533, 1005.7787286797039, 1008.848672699101, 1029.4183786160795, 1031.6801791444677, 10883.23817332779, 11403.714907127389, 11500.942495287638, 11692.883071169179, 11783.234089784137, 464547.0, 481513.0, 621999.0, 635596.0, 636366.0, 112125.0, 116878.0, 118271.40864295623, 118916.0, 119498.0, 1081.561967403203, 1092.3773449134076, 1133.3539882267194, 1139.1342579639534, 1144.5421831267392, 18399.448016559523, 18621.627567448846, 18781.530461738566, 19061.332853350064, 19276.036198190315, 45259.773701131286, 45675.31487027764, 47145.0, 47673.28490072583, 48172.0, 59362.40637593742, 68131.31868681249, 68797.3120268791, 68940.6552967232, 70511.0, 8620.224179823846, 8803.119688031145, 8914.331425143011, 8949.418287811272, 9193.77015574616, 9328.720138395858, 14226.786598201243, 14300.499482518062, 14649.780253295996, 15305.846941530888, 12233.3271670059, 12339.383030848601, 12449.25304481734, 12566.811497827357, 12857.448726055873, 11825.40872956366, 12101.213421127599, 12346.320952347707, 12509.687257818627, 12593.307368094476, 12569.994400448126, 12585.811212831632, 12635.873641263837, 12762.0, 12917.483300667867, 8972.910270897468, 8983.101689830964, 9085.409448385833, 9183.311251656287, 9313.767155821159, 10548.367097974146, 12203.755924881276, 12304.692382690504, 12481.582720251068, 12779.680507987374], "value": 9963.227070975396, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.1485108934635, 1468.8017117689112, 1475.1001888847802, 1477.2761346940003, 1490.3889405343807, 7616.65725042373, 7867.0, 7976.521408715481, 8387.28708059815, 8417.78955526112, 8074.555899425529, 8210.671573137068, 8244.54654993975, 8415.410921235502, 8416.747497575067, 2180.062573093573, 2228.4651683595903, 2230.475838178027, 2238.1942500699734, 2242.1703969531295, 2358.9410264743346, 2360.5160942006846, 2378.583747844129, 2396.0655344415604, 2398.6042303020067, 2285.908563657452, 2287.63397856343, 2330.2892617751577, 2332.1720789119872, 2336.8481048731865, 2244.887755612213, 2254.1434254983083, 2281.9201327953524, 2282.303897311336, 2290.4617414907484, 2320.153144102407, 2359.3393849722106, 2360.5750964826325, 2360.8229382796358, 2365.31405892291, 8392.916070839274, 8470.110638382943, 8555.10171431997, 8557.871631925464, 8585.613647385882, 5184.948150518484, 5333.786648534054, 5337.2527846101575, 5338.706371149585, 5344.732763361855, 3238.4170849247125, 3239.0, 3519.40170171069, 3520.718342532617, 3541.592716837545, 1302.2186687987228, 1319.1755153029358, 1344.206917918423, 1375.690469644332, 1376.6764810269578, 11371.464852245006, 11407.31556106628, 11434.599789007345, 11444.885551144465, 11505.942470287595, 4383.60547550722, 4669.929951050737, 4697.365855609517, 4724.622030237572, 4796.856094317176, 9778.0, 9790.265730070274, 9847.704568862946, 9847.80304393908, 9866.457344846101, 5394.0, 5398.24424580559, 5406.675599463966, 5434.239206511092, 5437.102878025161, 4266.146770645883, 4784.330193772875, 4878.292647566154, 4924.507549245119, 4971.1797553403285, 3962.227365663668, 4000.8599699010397, 4050.9594904051173, 4098.4876890388705, 4121.2993791055, 1370.9588712338646, 1371.3417559571462, 1372.444160115155, 1386.2999185411504, 1389.805427240187, 977.3451787302382, 977.5258999385293, 981.1513041219293, 1011.4437059617328, 1027.0807627173638, 5668.744906479218, 5695.829125126253, 5738.426157384228, 5739.655620662774, 5825.766969321265, 629.3234772619452, 634.2072409488139, 645.7062036773257, 650.8633187030777, 655.4395991427357, 3767.340715374853, 3789.052736815796, 3798.4302354647025, 3881.902952426212, 3914.7455415398476, 9261.814763704688, 9727.41635501858, 9943.453110079015, 10089.899101009043, 10127.544260508148, 10024.649137280161, 10034.548445319979, 10035.949820250851, 10053.346532475365, 10065.043820837009, 7737.961310193413, 7854.332381747501, 7898.9210107899335, 7958.761237162895, 7959.562224077616, 5464.672119672832, 5651.802186923603, 5678.176664383559, 5687.9431205687415, 5690.715464226855, 8997.55012249398, 9026.593803279, 9044.502552359683, 9078.863817042618, 9122.498262595622, 8881.42270752399, 8986.55067246622, 9003.954980225057, 9102.089791020844, 9141.3601047926, 6764.661766911733, 7000.299970003162, 7160.928390716234, 7343.889841652486, 7460.850782984418, 7895.921040789519, 8149.511029338259, 8169.142240064708, 8185.672573097009, 8231.588420579066, 7298.890516642361, 7337.70649174027, 7463.2163622818825, 7596.658150383357, 7605.619719014137, 7024.4029257513685, 7105.538140020882, 7116.786496405116, 7284.089488813898, 7325.926740732524, 6325.778597749057, 6427.453666438218, 6436.195475565554, 6437.098806167047, 6446.8388290293115, 4813.5186481352985, 4883.755812209446, 4894.339264199288, 4896.804127834989, 4979.825706100269, 4746.92879606813, 4751.5270266217585, 4785.832495862628, 4830.903381932412, 4854.90290194187], "value": 4458.576800086137, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.5040598439981, 396.7143656567272, 398.59343469660956, 406.59137566745414, 408.2956899348622, 5279.63042587019, 5442.15646574781, 5455.890882182354, 5549.556035517159, 5553.833384998451, 5302.946970530294, 5403.973245083432, 5457.2359869618185, 5464.754086066127, 5475.808346707866, 4289.849855255059, 4307.870763877088, 4309.439772829528, 4312.676549258803, 4350.934735978963, 3985.2428038672706, 4162.125953549758, 4305.526392096866, 4347.0, 4361.3676016977515, 4081.632653061224, 4111.177764447108, 4136.131412403384, 4142.212979533894, 4143.854965076223, 4116.238495878257, 4119.786566478152, 4129.525104612963, 4164.1879833432495, 4185.748855068675, 3656.6526180012856, 3664.3404187246283, 3679.2641471705765, 3680.429533422324, 3691.7969511676847, 5170.560502357304, 5271.655727789427, 5280.260763493114, 5301.0723123453245, 5327.668082979255, 4781.784819683123, 4808.831690890802, 4820.349252850855, 4824.710517368969, 4833.637477189225, 5599.412061733541, 5734.913976290359, 5753.453421924911, 5754.654720716729, 5771.182077645528, 3995.5005624296964, 4027.469561566615, 4034.253663072341, 4036.858709945167, 4037.212743515016, 5883.1760754837915, 5883.852903677357, 5930.805601927272, 5949.345571987078, 5961.761529538813, 4136.5522067276215, 4148.730332528376, 4157.563455837154, 4160.084781348099, 4199.202151591188, 4078.2455245779033, 4230.1539692061715, 4237.491501019897, 4254.170436764801, 4259.574042595777, 4793.712377257306, 4828.468868424541, 4839.830605928775, 4866.7079975201605, 4896.314515967767, 5682.857928551819, 5706.71466426677, 5707.514861236758, 5729.5702822288495, 5763.682997435181, 1359.327133069137, 1397.3153154954202, 1418.971620567583, 1426.7658475418689, 1428.6999730056596, 942.1944237676688, 971.4747845882039, 973.5570315506427, 988.0860204310984, 991.8742227571759, 5224.320838290998, 5237.292965449692, 5250.947490525123, 5263.421023687391, 5331.840044798585, 5650.434956504317, 5703.287089113861, 5720.513756330675, 5768.307803063659, 5784.479396854219, 5248.921266181011, 5370.9731451342495, 5402.297701298885, 5410.594205434608, 5439.700816455133, 3051.099925521932, 3056.465118604235, 3058.98470507646, 3062.402831447891, 3081.3221091359865, 5827.679477628772, 6006.579539432198, 6019.291318906509, 6040.637561746309, 6041.516678665696, 5767.991246084015, 5769.086611261287, 5777.942220577825, 5808.680522571215, 5862.746175872347, 5861.267341582387, 5901.498372638288, 5964.761409543657, 5970.253718285215, 5972.820815375546, 5525.778968841362, 5706.034306157181, 5731.19763233156, 5825.621334613236, 5888.646681199141, 5796.884062318646, 5805.3614102447855, 5809.360970293349, 5833.504152147115, 5839.737211825393, 5834.241548598655, 6115.602485838406, 6136.32500424944, 6138.785142519991, 6160.045192995093, 4065.10567675111, 4168.353905144708, 4168.749875007509, 4181.560936101666, 4231.936520952249, 5879.20630714848, 5882.617629854046, 5888.941110588839, 5897.3807750187325, 5902.645841249367, 5731.226284451713, 5758.510526605285, 5765.164051212634, 5773.0, 5784.161296611884, 5507.531859791962, 5667.2349232854685, 5699.772009119589, 5715.628484148517, 5720.399358067343, 5677.148427735872, 5692.715364231854, 5707.200991861226, 5717.1995920570325, 5746.798862039808, 5468.562514998711, 5692.150051233283, 5757.453041961007, 5771.422857714195, 5783.132530120515, 5538.83684426275, 5600.85997850057, 5609.747561359667, 5612.298462692163, 5663.631863928831], "value": 4347.049189443744, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.6380673470002, 743.8586668532978, 773.37743116791, 845.1928408370007, 863.4603372891941, 15656.452024179156, 16065.678686426265, 16760.16199190042, 16767.83232167681, 17148.971061736305, 17853.91073044633, 17961.371352002683, 17973.37093201738, 18128.0, 18185.36351227704, 746.1046743907306, 758.9089309282894, 771.3790398729021, 773.0993392697529, 773.5706682791049, 760.8326168243005, 764.0411283838774, 769.2576663519702, 769.3614300130878, 769.3806485778946, 817.7383237364046, 818.3207937411963, 819.692615269274, 820.8973878265217, 822.0505316359614, 811.926926576608, 815.1440986963694, 816.9019717633862, 819.1399031017468, 819.5820131732829, 597.0685730260807, 599.0953659973428, 599.9310079340888, 599.9400059994009, 601.1163589523437, 14453.277336133151, 14484.34820433083, 14495.0, 15141.091534507965, 15663.530094097196, 15325.770113448307, 15516.922415387851, 15767.605809854846, 15914.840851591567, 15948.362065517482, 12705.17416367933, 12822.42299096543, 12899.613011609667, 12906.80639790404, 13556.796648050287, 3821.178446633959, 3824.388097904322, 3854.9421758673643, 3893.143508428141, 4021.195760847842, 81001.0, 81961.0, 82610.58694706549, 82624.0, 82837.58581207116, 11729.706757330965, 11767.470463829148, 11893.0, 11924.821127683092, 11977.64067077989, 23408.18071367528, 23411.297661070195, 23601.7639823603, 23619.7638023621, 23671.408214794425, 8044.798880028046, 8067.260434598115, 8083.434159608771, 8145.307648849915, 8198.42611017235, 4373.825046998148, 4426.402435671208, 4472.575105364985, 4495.213337665896, 4536.070105628338, 3729.21686445844, 3743.419769935664, 3779.64093411131, 3804.144067584771, 3840.5007349044445, 639.0861068671751, 641.7368878759686, 656.0487293424553, 659.9736010559524, 665.7869481765886, 800.6917336825364, 818.5660265440841, 836.636063312461, 843.7131375332416, 866.8006358537572, 10282.640107596199, 10418.99218625418, 10453.372797632015, 10911.781764364827, 11389.373584452935, 789.9012623422072, 791.1890312429806, 798.7923139983646, 804.94365394423, 809.7246936041656, 5343.893122137535, 5349.491798279158, 5477.424870388551, 5516.724163791794, 5533.944660553423, 64514.0, 76666.23333766703, 79579.0, 80610.0, 81396.59301703454, 58333.708331458074, 58450.123248151314, 66175.66912165408, 66559.33440665629, 68332.0, 59961.70019149876, 60135.398646013855, 60327.69836150879, 60676.393236067954, 60977.0, 10927.672169834917, 11196.0, 11279.436028198721, 11460.0, 11470.827937580776, 41017.0, 41845.58154418417, 41906.0, 42261.15477690368, 42626.0, 51791.22313165381, 52656.0, 52786.082087685616, 53175.46824531705, 54113.458865410845, 13591.86408135906, 13833.584992450242, 13874.0, 13876.306184690928, 13934.721305573628, 14885.627859303604, 15164.18620320642, 15199.77200342018, 15272.0, 15291.89796817823, 12948.805767913676, 13049.34753262352, 13248.20510769357, 13397.33013349348, 13952.093113947967, 13938.945034623632, 14081.155130692192, 14098.12647656732, 14110.717785644434, 14280.928595356956, 14180.929095354457, 14241.715165696834, 14632.414703411745, 14659.633509162357, 15114.244287785346, 11122.277051991594, 11153.49809258569, 11172.273802202832, 11370.147238956946, 11729.765404691687, 11395.658130256106, 11428.714282143013, 11475.0, 11498.916134677684, 11597.362145082101], "value": 7477.197644248242, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 111.0, 113.0, 113.0, 116.0, 112.0, 114.0, 113.0, 111.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 122.0, 118.0, 159.0, 124.0, 116.0, 118.0, 117.0, 139.0, 121.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 163.0, 162.0, 166.0, 165.0, 164.0, 166.0, 162.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 111.0, 77.0, 76.0, 76.0, 76.0, 100.0, 77.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 102.0, 85.0, 86.0, 86.0, 97.0, 86.0, 83.0, 103.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 119.0, 119.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0, 148.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 98.0, 96.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 64.0, 57.0, 57.0, 61.0, 58.0, 57.0, 57.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 69.0, 73.0, 68.0, 69.0, 69.0, 68.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 90.0, 84.0, 87.0, 82.0, 94.0, 82.0, 81.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 129.0, 129.0, 140.0, 139.0, 124.0, 135.0, 173.0, 128.0], "value": 134.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 171.0, 184.0, 168.0, 168.0, 162.0, 165.0, 169.0, 161.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 67.0, 64.0, 63.0, 63.0, 62.0, 66.0, 62.0], "value": 63.9, "unit": "score"}]}]} 19:20:00 INFO - Return code: 0 19:20:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:00 ERROR - # TBPL SUCCESS # 19:20:00 INFO - Running post-action listener: _resource_record_post_action 19:20:00 INFO - [mozharness: 2017-01-06 03:20:00.077242Z] Finished run-tests step (success) 19:20:00 INFO - Running post-run listener: _resource_record_post_run 19:20:00 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 405069824; Read time: 12; Write time: 317872 19:20:00 INFO - TinderboxPrint: CPU usage
13.4% 19:20:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:20:00 INFO - TinderboxPrint: I/O write bytes / time
405,069,824 / 317,872 19:20:00 INFO - TinderboxPrint: CPU idle
8,241.2 (86.6%) 19:20:00 INFO - TinderboxPrint: CPU user
1,218.9 (12.8%) 19:20:00 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107618304; Read time: 0; Write time: 73980 19:20:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 297451520; Read time: 12; Write time: 243892 19:20:00 INFO - Running post-run listener: _upload_blobber_files 19:20:00 INFO - Blob upload gear active. 19:20:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:00 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 19:20:00 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'] 19:20:00 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 19:20:01 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:20:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:02 INFO - (blobuploader) - INFO - Done attempting. 19:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:02 INFO - (blobuploader) - INFO - Done attempting. 19:20:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:06 INFO - (blobuploader) - INFO - Done attempting. 19:20:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:20:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:20:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:07 INFO - (blobuploader) - INFO - Done attempting. 19:20:07 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:07 INFO - Return code: 0 19:20:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8f6e671628b165e9c99f16adb159f1dc788d6f630166ea400402ec011ed254ce8e6b9849f7d4893879815395ae1643bd3fab1fc64549cfb1d854c559b3160fd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/995ff8a33aba20ad96c306845afadf1a6fced87dda8deb2a33867fe465631603153bfecebcd16a72bbe734db92fb4be6c1869fa586c26a1f162eb419938e19c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fc2d866b1c97dd5a2009e8e2fe5de1331ca36467b44c291b56eed1ee9d95e4ca49c5a9ed7cdb34b909d171cd6546ae9f02d89180028efcd44438ff985c22d0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e1c14b84b52550b15412308225fa45efc000963ffd5c2c2a068fb925d4485d347b099b3559e55f68a084077590f7dec67c2425bd0f36740e6fb0c99359e0c31"} 19:20:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:07 INFO - Contents: 19:20:07 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8f6e671628b165e9c99f16adb159f1dc788d6f630166ea400402ec011ed254ce8e6b9849f7d4893879815395ae1643bd3fab1fc64549cfb1d854c559b3160fd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/995ff8a33aba20ad96c306845afadf1a6fced87dda8deb2a33867fe465631603153bfecebcd16a72bbe734db92fb4be6c1869fa586c26a1f162eb419938e19c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fc2d866b1c97dd5a2009e8e2fe5de1331ca36467b44c291b56eed1ee9d95e4ca49c5a9ed7cdb34b909d171cd6546ae9f02d89180028efcd44438ff985c22d0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e1c14b84b52550b15412308225fa45efc000963ffd5c2c2a068fb925d4485d347b099b3559e55f68a084077590f7dec67c2425bd0f36740e6fb0c99359e0c31"} 19:20:07 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:07 INFO - Copying logs to upload dir... 19:20:07 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.423954 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-01-05 19:20:06.532729) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 19:20:06.536566) ========= 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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8f6e671628b165e9c99f16adb159f1dc788d6f630166ea400402ec011ed254ce8e6b9849f7d4893879815395ae1643bd3fab1fc64549cfb1d854c559b3160fd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/995ff8a33aba20ad96c306845afadf1a6fced87dda8deb2a33867fe465631603153bfecebcd16a72bbe734db92fb4be6c1869fa586c26a1f162eb419938e19c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fc2d866b1c97dd5a2009e8e2fe5de1331ca36467b44c291b56eed1ee9d95e4ca49c5a9ed7cdb34b909d171cd6546ae9f02d89180028efcd44438ff985c22d0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e1c14b84b52550b15412308225fa45efc000963ffd5c2c2a068fb925d4485d347b099b3559e55f68a084077590f7dec67c2425bd0f36740e6fb0c99359e0c31"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011233 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1483659277/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d8f6e671628b165e9c99f16adb159f1dc788d6f630166ea400402ec011ed254ce8e6b9849f7d4893879815395ae1643bd3fab1fc64549cfb1d854c559b3160fd", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/995ff8a33aba20ad96c306845afadf1a6fced87dda8deb2a33867fe465631603153bfecebcd16a72bbe734db92fb4be6c1869fa586c26a1f162eb419938e19c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5fc2d866b1c97dd5a2009e8e2fe5de1331ca36467b44c291b56eed1ee9d95e4ca49c5a9ed7cdb34b909d171cd6546ae9f02d89180028efcd44438ff985c22d0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2e1c14b84b52550b15412308225fa45efc000963ffd5c2c2a068fb925d4485d347b099b3559e55f68a084077590f7dec67c2425bd0f36740e6fb0c99359e0c31"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 19:20:06.562451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 19:20:06.562843) ========= 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=826874ccc97bd7bf541b4875000001d5-1483671531.756580-2063748798 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 19:20:06.645151) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 19:20:06.645468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 19:20:06.645815) ========= ========= Total master_lag: 0.16 =========