builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-019 starttime: 1482649856.14 results: success (0) buildid: 20161224231016 builduid: 64329d631a4048fe88bc71ab8b30ee94 revision: d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.136928) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.137427) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.137832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.158613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.158983) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-24 23:10:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-12-24 23:10:56 (114 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.285261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.285588) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021819 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:56.335680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-24 23:10:56.336039) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 --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 d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 --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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-24 23:10:56,683 truncating revision to first 12 chars 2016-12-24 23:10:56,683 Setting DEBUG logging. 2016-12-24 23:10:56,683 attempt 1/10 2016-12-24 23:10:56,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d83cea57d22f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 23:10:58,053 unpacking tar archive at: mozilla-inbound-d83cea57d22f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.711188 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-24 23:10:58.060957) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:58.061263) ========= script_repo_revision: d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:58.061735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:58.062011) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 23:10:58.072875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-24 23:10:58.073207) ========= /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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:10:58 INFO - MultiFileLogger online at 20161224 23:10:58 in /builds/slave/test 23:10:58 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 23:10:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:10:58 INFO - {'append_to_log': False, 23:10:58 INFO - 'base_work_dir': '/builds/slave/test', 23:10:58 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 23:10:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:10:58 INFO - 'branch': 'Mozilla-Inbound', 23:10:58 INFO - 'buildbot_json_path': 'buildprops.json', 23:10:58 INFO - 'config_files': ('talos/linux_config.py',), 23:10:58 INFO - 'default_actions': ('clobber', 23:10:58 INFO - 'read-buildbot-config', 23:10:58 INFO - 'download-and-extract', 23:10:58 INFO - 'populate-webroot', 23:10:58 INFO - 'create-virtualenv', 23:10:58 INFO - 'install', 23:10:58 INFO - 'run-tests'), 23:10:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:10:58 INFO - 'download_minidump_stackwalk': True, 23:10:58 INFO - 'download_symbols': 'ondemand', 23:10:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:10:58 INFO - 'tooltool.py': '/tools/tooltool.py', 23:10:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:10:58 INFO - '/tools/misc-python/virtualenv.py')}, 23:10:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:10:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:10:58 INFO - 'installer_path': 'installer.exe', 23:10:58 INFO - 'log_level': 'info', 23:10:58 INFO - 'log_name': 'talos', 23:10:58 INFO - 'log_to_console': True, 23:10:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:10:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:10:58 INFO - 'opt_config_files': (), 23:10:58 INFO - 'pip_index': False, 23:10:58 INFO - 'sps_profile': False, 23:10:58 INFO - 'sps_profile_interval': 0, 23:10:58 INFO - 'suite': 'dromaeojs-e10s', 23:10:58 INFO - 'system_bits': '32', 23:10:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:10:58 INFO - 'title': 'talos-linux64-ix-019', 23:10:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:10:58 INFO - 'use_talos_json': True, 23:10:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:10:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:10:58 INFO - 'work_dir': 'build'} 23:10:58 INFO - [mozharness: 2016-12-25 07:10:58.513900Z] Running clobber step. 23:10:58 INFO - Running pre-action listener: _resource_record_pre_action 23:10:58 INFO - Running main action method: clobber 23:10:58 INFO - rmtree: /builds/slave/test/build 23:10:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:10:59 INFO - Running post-action listener: _resource_record_post_action 23:10:59 INFO - [mozharness: 2016-12-25 07:10:59.482874Z] Finished clobber step (success) 23:10:59 INFO - [mozharness: 2016-12-25 07:10:59.482974Z] Running read-buildbot-config step. 23:10:59 INFO - Running pre-action listener: _resource_record_pre_action 23:10:59 INFO - Running main action method: read_buildbot_config 23:10:59 INFO - Using buildbot properties: 23:10:59 INFO - { 23:10:59 INFO - "project": "", 23:10:59 INFO - "product": "firefox", 23:10:59 INFO - "script_repo_revision": "production", 23:10:59 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 23:10:59 INFO - "repository": "", 23:10:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 23:10:59 INFO - "buildid": "20161224231016", 23:10:59 INFO - "stage_platform": "linux64-pgo", 23:10:59 INFO - "basedir": "/builds/slave/test", 23:10:59 INFO - "buildnumber": 1821, 23:10:59 INFO - "platform": "ubuntu64_hw", 23:10:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:10:59 INFO - "slavebuilddir": "test", 23:10:59 INFO - "branch": "mozilla-inbound", 23:10:59 INFO - "revision": "d83cea57d22f0c74afa56611e6ce9ec6b2b25a92", 23:10:59 INFO - "slavename": "talos-linux64-ix-019", 23:10:59 INFO - "builduid": "64329d631a4048fe88bc71ab8b30ee94", 23:10:59 INFO - "repo_path": "integration/mozilla-inbound" 23:10:59 INFO - } 23:10:59 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 23:10:59 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 23:10:59 INFO - Running post-action listener: _resource_record_post_action 23:10:59 INFO - [mozharness: 2016-12-25 07:10:59.485346Z] Finished read-buildbot-config step (success) 23:10:59 INFO - [mozharness: 2016-12-25 07:10:59.485435Z] Running download-and-extract step. 23:10:59 INFO - Running pre-action listener: _resource_record_pre_action 23:10:59 INFO - Running main action method: download_and_extract 23:10:59 INFO - mkdir: /builds/slave/test/build/tests 23:10: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')]} 23:10:59 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:10:59 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:10:59 INFO - Downloaded 1361 bytes. 23:10:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 23:10:59 INFO - Using the following test package requirements: 23:10:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:10:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:10:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:10:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'jsshell-linux-x86_64.zip'], 23:10:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:10:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 23:10:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:10:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:10:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:10:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 23:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:10:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:10:59 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:10:59 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:11:02 INFO - Expected file size: 21831626 23:11:02 INFO - Obtained file size: 21831626 23:11:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:11:06 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 23:11:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:11:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 23:11:06 INFO - Expected file size: 21831626 23:11:06 INFO - Obtained file size: 21831626 23:11:11 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 23:11:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:11:11 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 23:11:14 INFO - Expected file size: 14409776 23:11:14 INFO - Obtained file size: 14409776 23:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:14 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:11:14 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:11:19 INFO - Downloaded 61370436 bytes. 23:11:19 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:11:19 INFO - mkdir: /builds/slave/test/properties 23:11:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:11:19 INFO - Writing to file /builds/slave/test/properties/build_url 23:11:19 INFO - Contents: 23:11:19 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 23:11:20 INFO - Running post-action listener: _resource_record_post_action 23:11:20 INFO - Running post-action listener: set_extra_try_arguments 23:11:20 INFO - [mozharness: 2016-12-25 07:11:20.065934Z] Finished download-and-extract step (success) 23:11:20 INFO - [mozharness: 2016-12-25 07:11:20.066163Z] Running populate-webroot step. 23:11:20 INFO - Running pre-action listener: _resource_record_pre_action 23:11:20 INFO - Running main action method: populate_webroot 23:11:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:11:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:11:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:11:20 INFO - '--tppagecycles', 23:11:20 INFO - '7'], 23:11:20 INFO - 'tests': ['tsvgm']}}, 23:11:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:11:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:11:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:11:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:11:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:11:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['damp', 'tps']}, 23:11:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'tests': ['damp', 'tps']}, 23:11:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['dromaeo_dom']}, 23:11:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:11:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['basic_compositor_video']}, 23:11:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:11:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'other-e10s': {'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['a11yr', 23:11:20 INFO - 'ts_paint', 23:11:20 INFO - 'tpaint', 23:11:20 INFO - 'sessionrestore', 23:11:20 INFO - 'sessionrestore_no_auto_restore', 23:11:20 INFO - 'tabpaint']}, 23:11:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['tsvgx', 23:11:20 INFO - 'tsvgr_opacity', 23:11:20 INFO - 'tart', 23:11:20 INFO - 'tscrollx', 23:11:20 INFO - 'cart', 23:11:20 INFO - 'tsvg_static']}, 23:11:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:11:20 INFO - 'tsvgr_opacity', 23:11:20 INFO - 'tart', 23:11:20 INFO - 'tscrollx', 23:11:20 INFO - 'cart', 23:11:20 INFO - 'tsvg_static']}, 23:11:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'talos_options': ['--disable-e10s'], 23:11:20 INFO - 'tests': ['tp5o']}, 23:11:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:11:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'talos_options': ['--disable-e10s', 23:11:20 INFO - '--xperf_path', 23:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:20 INFO - 'tests': ['tp5n']}, 23:11:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:11:20 INFO - 'talos_options': ['--xperf_path', 23:11:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:11:20 INFO - 'tests': ['tp5n']}}, 23:11:20 INFO - 'talos.zip': {'path': '', 23:11:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:11:20 INFO - Running post-action listener: _resource_record_post_action 23:11:20 INFO - [mozharness: 2016-12-25 07:11:20.083562Z] Finished populate-webroot step (success) 23:11:20 INFO - [mozharness: 2016-12-25 07:11:20.083651Z] Running create-virtualenv step. 23:11:20 INFO - Running pre-action listener: _resource_record_pre_action 23:11:20 INFO - Running main action method: create_virtualenv 23:11:20 INFO - Creating virtualenv /builds/slave/test/build/venv 23:11:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:11:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:11:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:11:20 INFO - Using real prefix '/usr' 23:11:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:11:20 INFO - Installing distribute.............................................................................................................................................................................................done. 23:11:22 INFO - Installing pip.................done. 23:11:22 INFO - Return code: 0 23:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:11:22 INFO - Reading from file tmpfile_stdout 23:11:22 INFO - Output received: 23:11:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:11:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:11:22 INFO - Using env: {'DISPLAY': ':0', 23:11:22 INFO - 'HOME': '/home/cltbld', 23:11:22 INFO - 'LANG': 'en_US.UTF-8', 23:11:22 INFO - 'LANGUAGE': 'en_US:en', 23:11:22 INFO - 'LOGNAME': 'cltbld', 23:11:22 INFO - 'MAIL': '/var/mail/cltbld', 23:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:22 INFO - 'MOZ_NO_REMOTE': '1', 23:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:22 INFO - 'NO_EM_RESTART': '1', 23:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:22 INFO - 'PWD': '/builds/slave/test', 23:11:22 INFO - 'SHELL': '/bin/bash', 23:11:22 INFO - 'SHLVL': '1', 23:11:22 INFO - 'TERM': 'linux', 23:11:22 INFO - 'TMOUT': '86400', 23:11:22 INFO - 'USER': 'cltbld', 23:11:22 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:22 INFO - '_': '/tools/buildbot/bin/python'} 23:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:11:23 INFO - Cleaning up... 23:11:23 INFO - Return code: 0 23:11:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:23 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:11:23 INFO - Using env: {'DISPLAY': ':0', 23:11:23 INFO - 'HOME': '/home/cltbld', 23:11:23 INFO - 'LANG': 'en_US.UTF-8', 23:11:23 INFO - 'LANGUAGE': 'en_US:en', 23:11:23 INFO - 'LOGNAME': 'cltbld', 23:11:23 INFO - 'MAIL': '/var/mail/cltbld', 23:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:23 INFO - 'MOZ_NO_REMOTE': '1', 23:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:23 INFO - 'NO_EM_RESTART': '1', 23:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:23 INFO - 'PWD': '/builds/slave/test', 23:11:23 INFO - 'SHELL': '/bin/bash', 23:11:23 INFO - 'SHLVL': '1', 23:11:23 INFO - 'TERM': 'linux', 23:11:23 INFO - 'TMOUT': '86400', 23:11:23 INFO - 'USER': 'cltbld', 23:11:23 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:23 INFO - '_': '/tools/buildbot/bin/python'} 23:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:23 INFO - Downloading/unpacking psutil>=3.1.1 23:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:11:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:25 INFO - Installing collected packages: psutil 23:11:25 INFO - Running setup.py install for psutil 23:11:25 INFO - building 'psutil._psutil_linux' extension 23:11:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:11:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:11:25 INFO - building 'psutil._psutil_posix' extension 23:11:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:11:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:11:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:11:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:11:25 INFO - Successfully installed psutil 23:11:25 INFO - Cleaning up... 23:11:25 INFO - Return code: 0 23:11:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:11:25 INFO - Using env: {'DISPLAY': ':0', 23:11:25 INFO - 'HOME': '/home/cltbld', 23:11:25 INFO - 'LANG': 'en_US.UTF-8', 23:11:25 INFO - 'LANGUAGE': 'en_US:en', 23:11:25 INFO - 'LOGNAME': 'cltbld', 23:11:25 INFO - 'MAIL': '/var/mail/cltbld', 23:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:25 INFO - 'MOZ_NO_REMOTE': '1', 23:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:25 INFO - 'NO_EM_RESTART': '1', 23:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:25 INFO - 'PWD': '/builds/slave/test', 23:11:25 INFO - 'SHELL': '/bin/bash', 23:11:25 INFO - 'SHLVL': '1', 23:11:25 INFO - 'TERM': 'linux', 23:11:25 INFO - 'TMOUT': '86400', 23:11:25 INFO - 'USER': 'cltbld', 23:11:25 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:25 INFO - '_': '/tools/buildbot/bin/python'} 23:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:11:28 INFO - Installing collected packages: mozsystemmonitor 23:11:28 INFO - Running setup.py install for mozsystemmonitor 23:11:28 INFO - Successfully installed mozsystemmonitor 23:11:28 INFO - Cleaning up... 23:11:28 INFO - Return code: 0 23:11:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:11:28 INFO - Using env: {'DISPLAY': ':0', 23:11:28 INFO - 'HOME': '/home/cltbld', 23:11:28 INFO - 'LANG': 'en_US.UTF-8', 23:11:28 INFO - 'LANGUAGE': 'en_US:en', 23:11:28 INFO - 'LOGNAME': 'cltbld', 23:11:28 INFO - 'MAIL': '/var/mail/cltbld', 23:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:28 INFO - 'MOZ_NO_REMOTE': '1', 23:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:28 INFO - 'NO_EM_RESTART': '1', 23:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:28 INFO - 'PWD': '/builds/slave/test', 23:11:28 INFO - 'SHELL': '/bin/bash', 23:11:28 INFO - 'SHLVL': '1', 23:11:28 INFO - 'TERM': 'linux', 23:11:28 INFO - 'TMOUT': '86400', 23:11:28 INFO - 'USER': 'cltbld', 23:11:28 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:28 INFO - '_': '/tools/buildbot/bin/python'} 23:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:28 INFO - Downloading/unpacking jsonschema==2.5.1 23:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:11:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:31 INFO - Downloading functools32-3.2.3-2.tar.gz 23:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:11:31 INFO - warning: no files found matching '*.txt' 23:11:31 INFO - no previously-included directories found matching 'build' 23:11:31 INFO - no previously-included directories found matching 'dist' 23:11:31 INFO - no previously-included directories found matching '.git*' 23:11:31 INFO - Installing collected packages: jsonschema, functools32 23:11:31 INFO - Running setup.py install for functools32 23:11:31 INFO - warning: no files found matching '*.txt' 23:11:31 INFO - no previously-included directories found matching 'build' 23:11:31 INFO - no previously-included directories found matching 'dist' 23:11:31 INFO - no previously-included directories found matching '.git*' 23:11:31 INFO - Successfully installed jsonschema functools32 23:11:31 INFO - Cleaning up... 23:11:31 INFO - Return code: 0 23:11:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:31 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:11:31 INFO - Using env: {'DISPLAY': ':0', 23:11:31 INFO - 'HOME': '/home/cltbld', 23:11:31 INFO - 'LANG': 'en_US.UTF-8', 23:11:31 INFO - 'LANGUAGE': 'en_US:en', 23:11:31 INFO - 'LOGNAME': 'cltbld', 23:11:31 INFO - 'MAIL': '/var/mail/cltbld', 23:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:31 INFO - 'MOZ_NO_REMOTE': '1', 23:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:31 INFO - 'NO_EM_RESTART': '1', 23:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:31 INFO - 'PWD': '/builds/slave/test', 23:11:31 INFO - 'SHELL': '/bin/bash', 23:11:31 INFO - 'SHLVL': '1', 23:11:31 INFO - 'TERM': 'linux', 23:11:31 INFO - 'TMOUT': '86400', 23:11:31 INFO - 'USER': 'cltbld', 23:11:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:31 INFO - '_': '/tools/buildbot/bin/python'} 23:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:11:31 INFO - Cleaning up... 23:11:31 INFO - Return code: 0 23:11:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:31 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:11:31 INFO - Using env: {'DISPLAY': ':0', 23:11:31 INFO - 'HOME': '/home/cltbld', 23:11:31 INFO - 'LANG': 'en_US.UTF-8', 23:11:31 INFO - 'LANGUAGE': 'en_US:en', 23:11:31 INFO - 'LOGNAME': 'cltbld', 23:11:31 INFO - 'MAIL': '/var/mail/cltbld', 23:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:31 INFO - 'MOZ_NO_REMOTE': '1', 23:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:31 INFO - 'NO_EM_RESTART': '1', 23:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:31 INFO - 'PWD': '/builds/slave/test', 23:11:31 INFO - 'SHELL': '/bin/bash', 23:11:31 INFO - 'SHLVL': '1', 23:11:31 INFO - 'TERM': 'linux', 23:11:31 INFO - 'TMOUT': '86400', 23:11:31 INFO - 'USER': 'cltbld', 23:11:31 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:31 INFO - '_': '/tools/buildbot/bin/python'} 23:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:31 INFO - Downloading/unpacking blobuploader==1.2.4 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:33 INFO - Downloading blobuploader-1.2.4.tar.gz 23:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:11:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:11:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:34 INFO - Downloading docopt-0.6.1.tar.gz 23:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:11:34 INFO - Installing collected packages: blobuploader, requests, docopt 23:11:34 INFO - Running setup.py install for blobuploader 23:11:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:11:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:11:34 INFO - Running setup.py install for requests 23:11:34 INFO - Running setup.py install for docopt 23:11:35 INFO - Successfully installed blobuploader requests docopt 23:11:35 INFO - Cleaning up... 23:11:35 INFO - Return code: 0 23:11:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:35 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:11:35 INFO - Using env: {'DISPLAY': ':0', 23:11:35 INFO - 'HOME': '/home/cltbld', 23:11:35 INFO - 'LANG': 'en_US.UTF-8', 23:11:35 INFO - 'LANGUAGE': 'en_US:en', 23:11:35 INFO - 'LOGNAME': 'cltbld', 23:11:35 INFO - 'MAIL': '/var/mail/cltbld', 23:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:35 INFO - 'MOZ_NO_REMOTE': '1', 23:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:35 INFO - 'NO_EM_RESTART': '1', 23:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:35 INFO - 'PWD': '/builds/slave/test', 23:11:35 INFO - 'SHELL': '/bin/bash', 23:11:35 INFO - 'SHLVL': '1', 23:11:35 INFO - 'TERM': 'linux', 23:11:35 INFO - 'TMOUT': '86400', 23:11:35 INFO - 'USER': 'cltbld', 23:11:35 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:35 INFO - '_': '/tools/buildbot/bin/python'} 23:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:35 INFO - Running setup.py (path:/tmp/pip-QfWLRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:35 INFO - Running setup.py (path:/tmp/pip-gaQJGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:35 INFO - Running setup.py (path:/tmp/pip-EfRlol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:35 INFO - Running setup.py (path:/tmp/pip-enEMJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:35 INFO - Running setup.py (path:/tmp/pip-NV3oyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:35 INFO - Running setup.py (path:/tmp/pip-jVQbZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:35 INFO - Running setup.py (path:/tmp/pip-QK_VYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:35 INFO - Running setup.py (path:/tmp/pip-zMgJva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:35 INFO - Running setup.py (path:/tmp/pip-tCmdJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:36 INFO - Running setup.py (path:/tmp/pip-DvbLsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:36 INFO - Running setup.py (path:/tmp/pip-aRCB5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:36 INFO - Running setup.py (path:/tmp/pip-H3pkYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:36 INFO - Running setup.py (path:/tmp/pip-Q3oMK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:36 INFO - Running setup.py (path:/tmp/pip-Eu_obo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:36 INFO - Running setup.py (path:/tmp/pip-4eeled-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:36 INFO - Running setup.py (path:/tmp/pip-SnOJMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:36 INFO - Running setup.py (path:/tmp/pip-Fraf6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:11:36 INFO - Running setup.py install for manifestparser 23:11:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:11:36 INFO - Running setup.py install for mozcrash 23:11:36 INFO - Running setup.py install for mozdebug 23:11:36 INFO - Running setup.py install for mozdevice 23:11:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozfile 23:11:37 INFO - Running setup.py install for mozhttpd 23:11:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozinfo 23:11:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozInstall 23:11:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozleak 23:11:37 INFO - Running setup.py install for mozlog 23:11:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for moznetwork 23:11:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozprocess 23:11:37 INFO - Running setup.py install for mozprofile 23:11:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:11:37 INFO - Running setup.py install for mozrunner 23:11:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:11:38 INFO - Running setup.py install for mozscreenshot 23:11:38 INFO - Running setup.py install for moztest 23:11:38 INFO - Running setup.py install for mozversion 23:11:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:11:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:11:38 INFO - Cleaning up... 23:11:38 INFO - Return code: 0 23:11:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:38 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:11:38 INFO - Using env: {'DISPLAY': ':0', 23:11:38 INFO - 'HOME': '/home/cltbld', 23:11:38 INFO - 'LANG': 'en_US.UTF-8', 23:11:38 INFO - 'LANGUAGE': 'en_US:en', 23:11:38 INFO - 'LOGNAME': 'cltbld', 23:11:38 INFO - 'MAIL': '/var/mail/cltbld', 23:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:38 INFO - 'MOZ_NO_REMOTE': '1', 23:11:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:38 INFO - 'NO_EM_RESTART': '1', 23:11:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:38 INFO - 'PWD': '/builds/slave/test', 23:11:38 INFO - 'SHELL': '/bin/bash', 23:11:38 INFO - 'SHLVL': '1', 23:11:38 INFO - 'TERM': 'linux', 23:11:38 INFO - 'TMOUT': '86400', 23:11:38 INFO - 'USER': 'cltbld', 23:11:38 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:38 INFO - '_': '/tools/buildbot/bin/python'} 23:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:11:38 INFO - Running setup.py (path:/tmp/pip-WuFyHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:11:38 INFO - Running setup.py (path:/tmp/pip-O0D0VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:11:38 INFO - Running setup.py (path:/tmp/pip-n1mm9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:11:38 INFO - Running setup.py (path:/tmp/pip-zby67i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:11:38 INFO - Running setup.py (path:/tmp/pip-_vqjav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:11:38 INFO - Running setup.py (path:/tmp/pip-RGAMaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:11:39 INFO - Running setup.py (path:/tmp/pip-vyCvFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:11:39 INFO - Running setup.py (path:/tmp/pip-9JpoiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:11:39 INFO - Running setup.py (path:/tmp/pip-1nZ1hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:11:39 INFO - Running setup.py (path:/tmp/pip-1_l_rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:11:39 INFO - Running setup.py (path:/tmp/pip-_ROBYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:11:39 INFO - Running setup.py (path:/tmp/pip-vYcZ0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:11:39 INFO - Running setup.py (path:/tmp/pip-bSYByl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:11:39 INFO - Running setup.py (path:/tmp/pip-QoYMwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:39 INFO - Running setup.py (path:/tmp/pip-QNbgfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:11:39 INFO - Running setup.py (path:/tmp/pip-CEijrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:11:39 INFO - Running setup.py (path:/tmp/pip-I1tVbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:11:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:11:41 INFO - Downloading blessings-1.6.tar.gz 23:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:11:41 INFO - Installing collected packages: blessings 23:11:41 INFO - Running setup.py install for blessings 23:11:42 INFO - Successfully installed blessings 23:11:42 INFO - Cleaning up... 23:11:42 INFO - Return code: 0 23:11:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:42 INFO - Reading from file tmpfile_stdout 23:11:42 INFO - Current package versions: 23:11:42 INFO - argparse == 1.2.1 23:11:42 INFO - blessings == 1.6 23:11:42 INFO - blobuploader == 1.2.4 23:11:42 INFO - docopt == 0.6.1 23:11:42 INFO - functools32 == 3.2.3-2 23:11:42 INFO - jsonschema == 2.5.1 23:11:42 INFO - manifestparser == 1.1 23:11:42 INFO - mozInstall == 1.12 23:11:42 INFO - mozcrash == 1.0 23:11:42 INFO - mozdebug == 0.1 23:11:42 INFO - mozdevice == 0.49 23:11:42 INFO - mozfile == 1.2 23:11:42 INFO - mozhttpd == 0.7 23:11:42 INFO - mozinfo == 0.9 23:11:42 INFO - mozleak == 0.1 23:11:42 INFO - mozlog == 3.4 23:11:42 INFO - moznetwork == 0.27 23:11:42 INFO - mozprocess == 0.23 23:11:42 INFO - mozprofile == 0.28 23:11:42 INFO - mozrunner == 6.13 23:11:42 INFO - mozscreenshot == 0.1 23:11:42 INFO - mozsystemmonitor == 0.3 23:11:42 INFO - moztest == 0.7 23:11:42 INFO - mozversion == 1.4 23:11:42 INFO - psutil == 3.1.1 23:11:42 INFO - requests == 1.2.3 23:11:42 INFO - wsgiref == 0.1.2 23:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:42 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 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:11:42 INFO - Using env: {'DISPLAY': ':0', 23:11:42 INFO - 'HOME': '/home/cltbld', 23:11:42 INFO - 'LANG': 'en_US.UTF-8', 23:11:42 INFO - 'LANGUAGE': 'en_US:en', 23:11:42 INFO - 'LOGNAME': 'cltbld', 23:11:42 INFO - 'MAIL': '/var/mail/cltbld', 23:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:42 INFO - 'MOZ_NO_REMOTE': '1', 23:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:42 INFO - 'NO_EM_RESTART': '1', 23:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:42 INFO - 'PWD': '/builds/slave/test', 23:11:42 INFO - 'SHELL': '/bin/bash', 23:11:42 INFO - 'SHLVL': '1', 23:11:42 INFO - 'TERM': 'linux', 23:11:42 INFO - 'TMOUT': '86400', 23:11:42 INFO - 'USER': 'cltbld', 23:11:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:42 INFO - '_': '/tools/buildbot/bin/python'} 23:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:11:42 INFO - Cleaning up... 23:11:42 INFO - Return code: 0 23:11:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x196c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cde480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:11:42 INFO - Using env: {'DISPLAY': ':0', 23:11:42 INFO - 'HOME': '/home/cltbld', 23:11:42 INFO - 'LANG': 'en_US.UTF-8', 23:11:42 INFO - 'LANGUAGE': 'en_US:en', 23:11:42 INFO - 'LOGNAME': 'cltbld', 23:11:42 INFO - 'MAIL': '/var/mail/cltbld', 23:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:42 INFO - 'MOZ_NO_REMOTE': '1', 23:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:11:42 INFO - 'NO_EM_RESTART': '1', 23:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:42 INFO - 'PWD': '/builds/slave/test', 23:11:42 INFO - 'SHELL': '/bin/bash', 23:11:42 INFO - 'SHLVL': '1', 23:11:42 INFO - 'TERM': 'linux', 23:11:42 INFO - 'TMOUT': '86400', 23:11:42 INFO - 'USER': 'cltbld', 23:11:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:42 INFO - '_': '/tools/buildbot/bin/python'} 23:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:11:42 INFO - Cleaning up... 23:11:42 INFO - Return code: 0 23:11:42 INFO - Running post-action listener: _resource_record_post_action 23:11:42 INFO - Running post-action listener: _start_resource_monitoring 23:11:42 INFO - Starting resource monitoring. 23:11:42 INFO - [mozharness: 2016-12-25 07:11:42.831057Z] Finished create-virtualenv step (success) 23:11:42 INFO - [mozharness: 2016-12-25 07:11:42.831582Z] Running install step. 23:11:42 INFO - Running pre-action listener: _resource_record_pre_action 23:11:42 INFO - Running main action method: install 23:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:11:43 INFO - Reading from file tmpfile_stdout 23:11:43 INFO - Detecting whether we're running mozinstall >=1.0... 23:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:11:43 INFO - Reading from file tmpfile_stdout 23:11:43 INFO - Output received: 23:11:43 INFO - Usage: mozinstall [options] installer 23:11:43 INFO - Options: 23:11:43 INFO - -h, --help show this help message and exit 23:11:43 INFO - -d DEST, --destination=DEST 23:11:43 INFO - Directory to install application into. [default: 23:11:43 INFO - "/builds/slave/test"] 23:11:43 INFO - --app=APP Application being installed. [default: firefox] 23:11:43 INFO - mkdir: /builds/slave/test/build/application 23:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:12:00 INFO - Reading from file tmpfile_stdout 23:12:00 INFO - Output received: 23:12:00 INFO - /builds/slave/test/build/application/firefox/firefox 23:12:00 INFO - Running post-action listener: _resource_record_post_action 23:12:00 INFO - [mozharness: 2016-12-25 07:12:00.451645Z] Finished install step (success) 23:12:00 INFO - [mozharness: 2016-12-25 07:12:00.451919Z] Running run-tests step. 23:12:00 INFO - Running pre-action listener: _resource_record_pre_action 23:12:00 INFO - Running main action method: run_tests 23:12:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:12:00 INFO - Python 2.7.3 23:12:00 INFO - Return code: 0 23:12:00 INFO - grabbing minidump binary from tooltool 23:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:12:00 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 0x1cdb820>, '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 0x1cdc520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cdc9b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:12:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:12:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:12:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:12:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:12:00 INFO - Return code: 0 23:12:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:12:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:12:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:12:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:12:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:12:00 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 23:12:00 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 23:12:00 INFO - Using env: {'DISPLAY': ':0', 23:12:00 INFO - 'HOME': '/home/cltbld', 23:12:00 INFO - 'LANG': 'en_US.UTF-8', 23:12:00 INFO - 'LANGUAGE': 'en_US:en', 23:12:00 INFO - 'LOGNAME': 'cltbld', 23:12:00 INFO - 'MAIL': '/var/mail/cltbld', 23:12:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:12:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:12:00 INFO - 'MOZ_NO_REMOTE': '1', 23:12:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:12:00 INFO - 'NO_EM_RESTART': '1', 23:12:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:12:00 INFO - 'PWD': '/builds/slave/test', 23:12:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:12:00 INFO - 'SHELL': '/bin/bash', 23:12:00 INFO - 'SHLVL': '1', 23:12:00 INFO - 'TERM': 'linux', 23:12:00 INFO - 'TMOUT': '86400', 23:12:00 INFO - 'USER': 'cltbld', 23:12:00 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122', 23:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:12:00 INFO - '_': '/tools/buildbot/bin/python'} 23:12:00 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/1482643811/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 23:12:00 INFO - mozversion application_buildid: 20161224213011 23:12:00 INFO - mozversion application_changeset: d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 23:12:00 INFO - mozversion application_display_name: Nightly 23:12:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:12:00 INFO - mozversion application_name: Firefox 23:12:00 INFO - mozversion application_remotingname: firefox 23:12:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:12:00 INFO - mozversion application_vendor: Mozilla 23:12:00 INFO - mozversion application_version: 53.0a1 23:12:00 INFO - mozversion platform_buildid: 20161224213011 23:12:00 INFO - mozversion platform_changeset: d83cea57d22f0c74afa56611e6ce9ec6b2b25a92 23:12:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 23:12:00 INFO - mozversion platform_version: 53.0a1 23:12:00 INFO - using testdate: 1482649920 23:12:00 INFO - actual date: 1482649920 23:12:00 INFO - starting webserver on 'localhost:54274' 23:12:00 INFO - SUITE-START | Running 2 tests 23:12:00 INFO - TEST-START | dromaeo_css 23:12:00 INFO - operating with platform_type : linux_ 23:12:00 INFO - Initialising browser for dromaeo_css test... 23:12:00 INFO - TEST-INFO | started process 26551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCTjMCl/profile http://localhost:54274/getInfo.html) 23:12:01 INFO - PROCESS | 26551 | 1482649921223 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 23:12:05 INFO - PROCESS | 26551 | __metrics Screen width/height:1600/1200 23:12:05 INFO - PROCESS | 26551 | colorDepth:24 23:12:05 INFO - PROCESS | 26551 | Browser inner width/height: 1024/697 23:12:05 INFO - PROCESS | 26551 | __metrics 23:12:06 ERROR - PROCESS | 26551 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 23:12:06 INFO - TEST-INFO | 26551: exit 0 23:12:06 INFO - Browser initialized. 23:12:06 INFO - Running cycle 1/1 for dromaeo_css test... 23:12:07 INFO - TEST-INFO | started process 26676 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCTjMCl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:18 INFO - PROCESS | 26676 | RSS: Main: 153989120 23:12:18 INFO - PROCESS | 26676 | 23:15:18 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54274/tests/dromaeo/cssquery-ext.html) 23:15:20 INFO - PROCESS | 26676 | RSS: Main: 163565568 23:15:20 INFO - PROCESS | 26676 | 23:17:07 INFO - PROCESS | 26676 | ************************* 23:17:07 INFO - PROCESS | 26676 | A coding exception was thrown and uncaught in a Task. 23:17:07 INFO - PROCESS | 26676 | 23:17:07 INFO - PROCESS | 26676 | Full message: ReferenceError: fetch is not defined 23:17:07 INFO - PROCESS | 26676 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 23:17:07 INFO - PROCESS | 26676 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 23:17:07 INFO - PROCESS | 26676 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 23:17:07 INFO - PROCESS | 26676 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:17:07 INFO - PROCESS | 26676 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:17:07 INFO - PROCESS | 26676 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:17:07 INFO - PROCESS | 26676 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 23:17:07 INFO - PROCESS | 26676 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 23:17:07 INFO - PROCESS | 26676 | TaskImpl@resource://gre/modules/Task.jsm:277:3 23:17:07 INFO - PROCESS | 26676 | asyncFunction@resource://gre/modules/Task.jsm:252:14 23:17:07 INFO - PROCESS | 26676 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 23:17:07 INFO - PROCESS | 26676 | 23:17:07 INFO - PROCESS | 26676 | ************************* 23:18:22 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-ext.html (next: http://localhost:54274/tests/dromaeo/cssquery-jquery.html) 23:18:22 INFO - PROCESS | 26676 | RSS: Main: 160415744 23:18:22 INFO - PROCESS | 26676 | 23:21:24 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54274/tests/dromaeo/cssquery-mootools.html) 23:21:25 INFO - PROCESS | 26676 | RSS: Main: 165564416 23:21:25 INFO - PROCESS | 26676 | 23:24:27 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54274/tests/dromaeo/cssquery-prototype.html) 23:24:28 INFO - PROCESS | 26676 | RSS: Main: 160092160 23:24:28 INFO - PROCESS | 26676 | 23:27:30 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54274/tests/dromaeo/cssquery-yui.html) 23:27:31 INFO - PROCESS | 26676 | RSS: Main: 164827136 23:27:31 INFO - PROCESS | 26676 | 23:30:33 INFO - PROCESS | 26676 | Cycle 1(1): loaded http://localhost:54274/tests/dromaeo/cssquery-yui.html (next: http://localhost:54274/tests/dromaeo/cssquery-dojo.html) 23:30:34 INFO - PROCESS | 26676 | RSS: Main: 164691968 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | __start_tp_report 23:30:34 INFO - PROCESS | 26676 | _x_x_mozilla_page_load 23:30:34 INFO - PROCESS | 26676 | _x_x_mozilla_page_load_details 23:30:34 INFO - PROCESS | 26676 | |i|pagename|runs| 23:30:34 INFO - PROCESS | 26676 | |0;dojo.html;3169.302753394253;3172.762042846787;3191.2341038150835;3210.8555115019813;3215.646278909318;5208.348956380452;5318.441563635821;5319.441458646845;5375.543078838298;5409.134538473845;5243.79024839007;5297.258383826267;5316.893662126755;5354.625176237665;5454.963556924192;4817.951820481803;4858.4898585648525;4891.021795640868;4971.726555039471;4986.276989836471;4770.761461926907;4858.368412106439;4862.78117484714;4883.731394773286;4897.559219670229;4646.1404640141345;4863.513648635108;4876.21980483121;4902.583280421169;4973.403191617011;4644.907101857978;4840.177169881129;4874.1713908635265;4894.412670479565;4968.031233909389;4870.9269360959615;4871.732054736988;4879.048585525824;4905.313256144107;4931.358923339979;5348.919766203471;5354.330708661418;5391.919121213145;5414.052540805383;5418.9458105418835;5596.132599447093;5597.776088956478;5737.827865164052;5757.539396848284;5768.653880767168;5860.120981852713;5865.384134665841;5867.76528938842;5879.265091863517;5921.496672782819;5397.649152805055;5421.918671219935;5426.430224826416;5429.4299098594865;5429.782808687608;5704;5728.513076388554;5813.128030795415;5830.125481177773;5955.225820643375;5656.943430565724;5657.9434205658235;5669.31968163823;5699.515541178963;5736.340320863071;5690.430956904359;5716.857078573069;5739.512141467939;5749.88500229993;5770.105633626795;5475.206095116186;5521.4478552145265;5684.886302274014;5732.885342293131;5737.3688894221605;5755.683437410982;5798.275215598051;5819.912701309484;5822.563307751935;5848.736806843703;4324.17840610283;4332.653387728974;4332.783360831946;4341.0666706657585;4371.803268852909;3753.8123093845197;3779.603141670085;3802.4866643002842;3831.9616803832164;3890.124721937598;5462.590305727086;5473.261109750133;5506.91739623906;5511.3937466878615;5531.9723401382735;5701.71491425427;5717.885642287131;5726.77092916287;5737.3688894221605;5758.539316854642;5337.466253374631;5341.065313570188;5404.351477822608;5478.616496845262;5488.231647569343;4758.881027974328;4779.569838714532;4784.593309568726;4800.807967681323;4828.348172996601;6040.335563088057;6044.455998960027;6046.818595442144;6049.697515124226;6050.576459647782;5572.4148964358155;5790.623609465371;5808.5353171747;5815.447532484407;5822.592418530662;5818;5897.764089436546;5920.467157955804;5928.407159284037;6005.219321488354;5558;5603.607747457639;5610.298712660918;5612.579056570692;5622.971885140548;5690.260266165372;5694.715264236855;5716.771329146787;5746.511546518593;5749.741261643319;5469.2616496772425;5500.7524661391135;5506.174073888948;5555.111182210827;5565.526930210977;5537.280153580009;5567.526760225426;5578.748956297058;5580.35825880029;5581.358143813513;5533.972330138324;5537.501624853782;5539.833804985857;5545.7781688732;5569.637973531708;5706.600537962303;5739.8852022960145;5741.712914354182;5755;5787.536997040311;5586.636868603528;5596.832095037155;5610.579206559442;5620.409856965123;5633.380328163817 23:30:34 INFO - PROCESS | 26676 | |1;ext.html;530.3609150973077;548.1585765849419;549.7636016512903;568.3549171690133;571.7570032736086;40381.394279085835;43042.35436468456;43192.352114718306;46499.535004649944;46526.53473465273;44476.110477790506;44553;44803.55196448034;44820.32769508452;44985;8918.197362237397;9044.909550904473;9063.093690630918;9087.363884528086;10058.195344372469;9995.2003839693;10401.583936642524;10411.2191585631;10412.479376031206;10453;10472.2145839062;10479.790404191912;10521.368717876914;10541.417352748587;10548;10438.843417348746;10485.056344928993;10489.370637761758;10507.527161277761;10516.579336826519;10198.949005255;10257.230707696877;10264.64073757415;10276.177905767521;10312.845307320396;37883.43174852379;37943;37964.62035379666;38394;38589;4127.607877251657;4156.750594964312;4225.577442255781;4238.957610423887;4262.595053469915;28902.566461503095;29107.417851642847;29249.707502925125;29911.252218694488;30527;4212.599803018708;4258.659307255412;4332.1985432695055;4360.4331436913;4392.297232442826;302128;304100;304343;305387;305809;45419.54580454219;45665;45730.54269457329;46623.53376466192;47180;55132.44867551354;55352.44647553554;55668.72165639227;55756;55820;14790;15318.770218446733;15356.464353564554;15375.154366509949;15429.38282468711;10581.470926453647;10706.357618542912;11356.829647555292;11528;11550.826737598944;17128.40050598248;17212.225449854788;17263.654726905643;17274.481765547054;17301.3944511944;6573.704183311763;6789.6605169741315;6866.725330986705;6898.310168983062;6907.13660792401;895.7626229049307;911.7720569857536;919.4069824962995;930.9255259579218;959.6737109382843;30425.391492170034;31025.534616980764;31056.844715776275;31058.223544411572;31648.525272120936;981.3670182732097;1085.6200329884634;1087.5106202209038;1088.6244245735368;1101.961431349899;15694.293756780973;16293.918530407273;16426.342946282253;16427.425040123537;16472.588185295463;484347;485740;486338;489713;490035;59522.70238648866;59967.40032599618;60127.39872601218;60133.69933150306;60222.698886505284;1359.9932000340134;1382.7303675783126;1391.7912313153108;1393.0457636519018;1401.2153194211273;27526.449471010867;29382.706172937997;29975;30140.698593014666;30709.38581228318;45540.31689524726;50385.49614503955;50543;50834.745826270635;51021.48978510167;413906;415615;416540;416702;419824;25368.365790855376;25902.87048564745;26017.83714605654;26533.204003879913;27391.315217119733;41917;42301.78849105735;42879.3568096485;43582.34626480542;43918.780406097765;35899.92300230997;35948.82025589855;36027.63972360243;36033.81983090068;36135.819320903225;35292.47061294134;35624.109397265274;35924.4611330825;35936;35964.28071438609;35484.46773298351;35542.28915421729;35732.46401304034;35792;36593.26813463769;30352;32534.511982320757;32650.673493265713;32890;32953;22887.885560572755;22933.655995159752;23154.885396917132;23257.3022809316;23505.882470587538 23:30:34 INFO - PROCESS | 26676 | |2;jquery.html;394.66847847807855;398.1221406798011;404.3227593780417;410.772021528052;422.8393210579979;17388;18264.634707305846;18462.90768546159;18726;18819.34132305368;17937;17997.820021799776;18034.63930721388;18136.727949080774;18189.7271540927;5552.361478429982;5580.218769372291;5666.941680500203;5720;5757.42425757423;5637.267155269829;5661.037623603997;5690.516306113965;5697.401772813857;5704.20141180233;5448.346198456171;5524.4199359067325;5636.915446268309;5647.265855438787;5661.886762264773;5639.407862174474;5667.206591077232;5668.348139963895;5674.801381951653;5686.715664216814;5045.772940217699;5114.514121158484;5236.293100431431;5275.709835959021;5354.973225133888;13106.2136271824;13411.530596429177;13420.261885596288;13495.190288582717;13546.45814167432;2515.74202462067;2529.9747002530107;2601.856897870616;2628.4348864993926;2630.8947642094486;9453.723747294096;9780.168685661727;10070.395776253448;10195.79608407835;10333.948330258376;1927.2194761121768;1929.4693959161343;1950.1419375474745;1950.697641865513;1953.4237399967044;69917.65041174812;76272.61863690702;77272;77856.61071694661;78374.608126959;10512.842307365396;10705.785884282272;10865.782684346268;10935.617253396093;10988;14820.703585928437;14828.406863725546;14854.108753474826;14862.405503779728;14864.18246996404;7415.1843297237265;7564.735234266775;7645.426593005435;7833.454127408615;7849.960750196213;6995.720171193197;7051.224365319811;7152.213256541776;7314.96342518284;7369.189389167187;4580.244259697112;4686.250199967989;4713.363695901009;4720.409948756405;4816.373871396766;1947.7565304336958;1990.6416844967755;2007.7289565908416;2023.6660950943224;2065.173930427841;855.4012191465998;855.7475544714324;859.0936561927102;861.7414775567305;888.760034790603;17052.65894682124;17133.57166070858;17512;17748.29006839712;18159.45521634353;856.2037305306125;865;866.8829707989466;885.6191837509804;887.1970866365949;9495.857562136573;9904.108630223314;9943.900560994442;10043.146332561813;10151;424393;430925;433032;433807;434055;77103.61448192835;77886;78192.60903695445;78432;81006.59496702597;982.0915653020927;992.3847214726771;1014.0417305646093;1033.9121174700235;1034.730969947804;14750.704985900436;15518.068915865084;16168.919155404148;16276.918615406847;16529.33882644681;39936.60063399329;40409.39385909273;40789;40973.795131024155;41070.38394424026;48649;48926.510734892196;52271.21593176181;52475.47524524706;54673.45326546684;8042.115367309708;8146.266835984789;8293.58532073406;8341.41610087288;8418.368622353226;10502.369857808557;12108.636740897788;12455.813162802746;12604.810927836274;12736.936315318364;10854.782904342026;10909.781804363709;11321.150913681671;11414.14393920476;11663.183577149857;10629.096526795309;10736.355818650905;10871.565137394417;11156.944215279196;11289.379084150121;10957.83563246536;11065.114790816877;11268;11380.531856508573;11437.771244575228;8141.59292035384;8151.918480815353;8229.177082291963;8258;8730.214280714512;10574.735631609272;11255.774884502427;11277.041451476389;11278.49246783913;11650.941745291559 23:30:34 INFO - PROCESS | 26676 | |3;mootools.html;1368.7809950407936;1389.1595584671275;1390.10338331776;1390.131168019988;1397.5318268380095;7609.581473018981;7799.10310314314;8296.29481494073;8686.695965641204;8691.391602587813;8148.7147949821765;8393.328533717304;8397.958010209955;8617.396782225247;8650.56747162641;2336.6495025746103;2388.9283321500297;2395.401149712572;2396.3290278721984;2397.9280621581293;2327.290176496168;2393.772591603795;2396.6405039244164;2410.2889647553925;2410.349205714465;2314.918977835776;2321.129576408847;2338.1348900906687;2343.894524746382;2350.3654013416367;2278.4873403484085;2282.6347784354507;2307.5846347657416;2310.3992961829877;2310.7111611048617;2256.3907744908784;2324.8837558122027;2336.158982766203;2340.379799353173;2359.433735903388;8748.60631271588;8808.075152109064;8819.426737262058;8828.779280517974;8834.955825220897;5263.368395792491;5408.59435542332;5426.484483974016;5430.5927055471;5430.809921652763;3455.2053027803745;3471.687548120656;3559.056849934769;3707.7589956653;3750.306193354238;1425.6150839273441;1451.637090727318;1460.6640472691233;1492.1812090544936;1504.2027725305607;11279.943600282028;11321.716957076056;11421.714457138554;11495.2528085675;11532.94233528827;4437.400950871655;4462.44219472566;4528.343390208402;4580.862574122782;4593.402857628487;9155.084491550924;9199.356045076915;9201.631934722536;9220.400673956305;9229.861552076723;5145.742712864342;5195.350581177353;5225.451327610622;5245.029669511153;5276.39321478035;4817.27740838877;4886.120498310267;4962.230854217602;4999;5057.620678449131;3704.49989251453;3768.491253680718;3791.545014598266;3841.8655347063277;3922.686185105185;1166.1021013819482;1167.737259116692;1170.0814860334597;1183.7632473505334;1188.5424111716877;958.8705524754208;979.5934687104772;990.6235630460379;997.6308765756571;1004.8995100490039;5629.943700563023;5793.478586927113;5892.882142357214;5954.880902382015;5979.491743201789;580.5152697497552;582.6009183709173;582.7639805878626;587.5094296262583;587.8207146820175;3931.5282166140246;4030.435738996543;4051.1897620476025;4114.753114813121;4156.625903668684;10157.238207134495;10531.104856087166;10549.419781911914;10551.630692925712;10581.947090264654;10335.689929302132;10425.322354046963;10450.477476126163;10450.738731531772;10526.473676316153;7776.650050747843;7863.174366691529;8009.31920786728;8013.198680132056;8047.637856296364;5755.424457554379;5797.8550536236935;5842.737076831468;5843.328017277898;5856.677882716492;9086.863697044682;9167.908320916706;9266.11971862672;9361.4383137012;9427.245820334494;9634.421934683942;9646.758831029;9731.756706082122;9733.756656083371;9739.172170365315;6495.642739649365;6647.634380108947;6955.1653801544135;7073.6816843243205;7177.031100801325;7933.880991785243;8265.173482651686;8267.173282671685;8268.545230012407;8282.378821588285;7460.328570428687;7466.216047315171;7564.50830696003;7607.809804754704;7661.578613176329;7429.7399591014055;7443.55338679681;7468.589227592317;7471.8879216812875;7474.289942455458;6461.127747753993;6578.901316480161;6582.868342633216;6589.6375699336995;6594.406503414716;4857.635677324144;4893.09477746618;4910.165271903714;4972.179590367549;5062.2406639002975;4890.462049174517;4900.117978763857;5035.420926593342;5040.546350828443;5059.215821547666 23:30:34 INFO - PROCESS | 26676 | |4;prototype.html;381.4583291725749;395.97228194026417;406.90030942419105;408.51590864825164;408.9795510224488;5258.342707161605;5323.520883120524;5369.355677318722;5437.999999999995;5443.972780136104;5278.392984806748;5281.128613778722;5348.197770334452;5351.866203344918;5359.866003349918;4141.62725354718;4158.858188033166;4162.313218318989;4199.580041995807;4207.579242075783;4010.3382941814566;4099.3031184698675;4187.120704652027;4195.349720793282;4214.536400995888;4106.445629839978;4111.60939710727;4125.64931980782;4128.69034822388;4138.482689663792;4122.876313710563;4127.504699436087;4130.091379896418;4139.254934111858;4143.192077544895;3670.100825297819;3684.9180213179466;3690.8708195213308;3709.0727318170457;3710.90333961311;4937.432195297551;5092.261622064817;5143.562797162245;5192.636515443921;5286.259923610659;4740.7200055984695;4749.976250118727;4764.809407623691;4767;4782.760861956923;5532.723363831792;5537.169424586304;5553.861153471155;5554.805581804658;5582.358028826697;3944.270309992632;3989.142334398089;4031.778252196128;4036.5761595032686;4047.9797601011805;5727.684977326246;5780.132980052984;5815.621984571032;5824.912626310609;5828.6502809831545;4124.711270211116;4126.587341265849;4131.690123240709;4139.97930010348;4144.730592511477;4054.56725964419;4164.187983343219;4182.2681030820095;4200.768957707355;4211.115665710174;4716.335270572115;4717.599004084623;4727.834525791581;4738.73936933465;4749.335093086971;5565.9443405566235;5572.721363931787;5587.608867379245;5608.831735047955;5624.268845050199;1369.274284629148;1398.3427788939182;1410.4781230944411;1412.7174565087023;1416.390951890697;959.3236768078488;976.72651657536;986.6250824686573;988.229181238635;992.2260636703382;5185.740712964337;5243.737813109406;5245.1345527988215;5249.42256351801;5266.157414813688;5478.698671573102;5484;5503.944960550423;5512.952539017574;5531.640443371168;5111.591072714248;5221;5227.484029631364;5287.4976877196605;5417.993013877257;2968.3618022125347;2969.2280007198283;2974.9107526774233;2983.7165469280385;2990.401919616042;5803.361630220672;5816.88366232673;5868.970655146697;5884.676342801102;5918.585699001029;5466.890662186734;5634.887302253932;5654.349749778746;5706.771729130788;5721.313442386941;5799.52028782727;5826.592138550261;5850.707464626752;5857.47282744555;5898.8230353089475;5300;5542.944570554243;5552.278203833475;5561.860953476195;5695;5514.283143191359;5532.699815543264;5535.785685725742;5546.58400619947;5563.638363506359;5849.210356601804;5864.589478736448;5884.941150588611;5949.791757288474;5967.582269241111;3904.6095390460728;3978.7015973801504;4013.9598604013586;4016.718829681894;4031.1534577739003;5551.805686800943;5583.4595259157795;5624.915626265692;5637.590602349413;5667.874547044858;5571.303587051619;5635.802746903839;5678.217673340117;5692.203091567267;5713.3149118818965;5593.804216852391;5604.495595396434;5626.874625075083;5637.633553818989;5637.661740295595;5486.862828429162;5492.945070549403;5537.1140617501005;5590.440955904377;5618.4662457066515;5609.411011843861;5631.128124656373;5660.632058916157;5663.612414958362;5674.943250567441;5387.973060134675;5470.72646367688;5518.862028449321;5535.169724541351;5546.889062218814 23:30:34 INFO - PROCESS | 26676 | |5;yui.html;738.6786747764726;746.6117618838208;766.6511737159593;819.1358117186368;858.5192292316303;15176.2411879406;16611.584710382245;17812.910935445303;18746.062696865138;18855.90572047141;15390.692186156299;15531.68936621267;15626.843731562682;16788.99266044038;17017.57456063599;788.113372455987;814.5764202614637;819.9057108432518;825.859603867341;828.772087675888;786.2137862137862;806.7741032510877;812.7927378518469;818.3657665309403;848.367965865428;873.7335112790596;877.3595275448926;880.8018195905934;881.5195718333524;881.7002219245455;867.2411639815161;868.6134670071821;874.2001069021837;874.7681864305964;876.0538618292229;635.4598591197491;638.8818068657313;641.4066988036049;641.8427485266093;642.0497663458061;15391.230438478144;15792.763108553381;16306.347746090141;16345.34618615254;16369.345226190939;14421;14638.634034149125;14686.779698304534;15004.099754014795;15165.393384264617;12488.066259128345;12529.74940501185;12533.498660053587;12611;12652.557160499431;3789.829457674411;3789.9431508527396;3794.354959656865;3833.6358045985585;3833.9233215335817;76031.61984190099;76929;78111.60944195243;78994;80417.59791201007;11279.616115165418;11310.208285420105;11318.547258109747;11449.59926402572;11659.183857130089;21255.68116478254;21346.466338341666;21367;21480.57038859214;21494.462638434165;7958.960205198937;7988.121306656263;8056.194380561897;8104.675812967415;8428.199321064489;4374.234508960919;4395.428594282786;4407.140607581555;4421.911561768747;4439.400680908101;3716.4239542870896;3721.292954338667;3752.2683076799763;3761.1161377076364;3812.7140464465274;644.3298969072206;647.5984889368622;650.5316172355896;653.5588477777576;665.8035879415584;704.5244459989486;708.6102643546029;726.2664708644307;734.3611058379245;753.9547627142389;10031;10275.74310642225;10334.948325258325;10534.262601617813;10542.947285263677;717.8907497198771;717.989230161548;734.4565021883767;739.7189068154174;743.1676522295064;4657.743824089707;4963.7224444422645;5003.624728145403;5014.34813474246;5027.145385284511;78973.60513197513;78974;82401;82918.58540707378;84000.55740250525;64650.0302495463;72160;72949.27050729531;73435.63282183555;73994;63311.050334245025;64937.67531162314;65218.67390663016;65545;65834.67082664651;9464;9628.75928101803;9661.178799802095;9680.612775488902;9790.951045245014;43793.56206437895;43793.99999999873;44994.77502612466;45014;47184;55967;56594.15108773289;56747.432525674216;57220.1416978754;58441.4155858453;11986.940065299617;12016.939915300367;12017.459214335551;12105.939470302945;12114.394280285775;13656.590302290948;13709.794353084511;14071.155730656194;14150.78773818373;14372.425102995763;12294.446749896455;12764.936175319064;13156.144850584682;13300.40148193353;13745.450181992608;13239.801402979156;13437.126586771828;13568.118072325267;13718.65703357424;14013.929930350283;12764.680882978;12873.549425770054;12956.481740730265;13422.664433389244;13424.463021479032;10842.837357439486;10850.457477125954;10923.399213043358;11151.776964460827;11600.187986841178;9708.854367184356;10061.899381006097;10125.746856328651;10156.289059765746;10170 23:30:34 INFO - PROCESS | 26676 | __end_tp_report 23:30:34 INFO - PROCESS | 26676 | __start_cc_report 23:30:34 INFO - PROCESS | 26676 | _x_x_mozilla_cycle_collect,313 23:30:34 INFO - PROCESS | 26676 | __end_cc_report 23:30:34 INFO - PROCESS | 26676 | __startTimestamp1482651034141__endTimestamp 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | ------- Summary: start ------- 23:30:34 INFO - PROCESS | 26676 | Number of tests: 6 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#0] dojo.html Cycles:165 Average:5328.81 Median:5537.50 stddev:615.67 (11.1%) stddev-sans-first:593.80 23:30:34 INFO - PROCESS | 26676 | Values: 3169.3 3172.8 3191.2 3210.9 3215.6 5208.3 5318.4 5319.4 5375.5 5409.1 5243.8 5297.3 5316.9 5354.6 5455.0 4818.0 4858.5 4891.0 4971.7 4986.3 4770.8 4858.4 4862.8 4883.7 4897.6 4646.1 4863.5 4876.2 4902.6 4973.4 4644.9 4840.2 4874.2 4894.4 4968.0 4870.9 4871.7 4879.0 4905.3 4931.4 5348.9 5354.3 5391.9 5414.1 5418.9 5596.1 5597.8 5737.8 5757.5 5768.7 5860.1 5865.4 5867.8 5879.3 5921.5 5397.6 5421.9 5426.4 5429.4 5429.8 5704.0 5728.5 5813.1 5830.1 5955.2 5656.9 5657.9 5669.3 5699.5 5736.3 5690.4 5716.9 5739.5 5749.9 5770.1 5475.2 5521.4 5684.9 5732.9 5737.4 5755.7 5798.3 5819.9 5822.6 5848.7 4324.2 4332.7 4332.8 4341.1 4371.8 3753.8 3779.6 3802.5 3832.0 3890.1 5462.6 5473.3 5506.9 5511.4 5532.0 5701.7 5717.9 5726.8 5737.4 5758.5 5337.5 5341.1 5404.4 5478.6 5488.2 4758.9 4779.6 4784.6 4800.8 4828.3 6040.3 6044.5 6046.8 6049.7 6050.6 5572.4 5790.6 5808.5 5815.4 5822.6 5818.0 5897.8 5920.5 5928.4 6005.2 5558.0 5603.6 5610.3 5612.6 5623.0 5690.3 5694.7 5716.8 5746.5 5749.7 5469.3 5500.8 5506.2 5555.1 5565.5 5537.3 5567.5 5578.7 5580.4 5581.4 5534.0 5537.5 5539.8 5545.8 5569.6 5706.6 5739.9 5741.7 5755.0 5787.5 5586.6 5596.8 5610.6 5620.4 5633.4 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#1] ext.html Cycles:180 Average:55514.70 Median:35912.19 stddev:108501.32 (302.1%) stddev-sans-first:108727.16 23:30:34 INFO - PROCESS | 26676 | Values: 530.4 548.2 549.8 568.4 571.8 40381.4 43042.4 43192.4 46499.5 46526.5 44476.1 44553.0 44803.6 44820.3 44985.0 8918.2 9044.9 9063.1 9087.4 10058.2 9995.2 10401.6 10411.2 10412.5 10453.0 10472.2 10479.8 10521.4 10541.4 10548.0 10438.8 10485.1 10489.4 10507.5 10516.6 10198.9 10257.2 10264.6 10276.2 10312.8 37883.4 37943.0 37964.6 38394.0 38589.0 4127.6 4156.8 4225.6 4239.0 4262.6 28902.6 29107.4 29249.7 29911.3 30527.0 4212.6 4258.7 4332.2 4360.4 4392.3 302128.0 304100.0 304343.0 305387.0 305809.0 45419.5 45665.0 45730.5 46623.5 47180.0 55132.4 55352.4 55668.7 55756.0 55820.0 14790.0 15318.8 15356.5 15375.2 15429.4 10581.5 10706.4 11356.8 11528.0 11550.8 17128.4 17212.2 17263.7 17274.5 17301.4 6573.7 6789.7 6866.7 6898.3 6907.1 895.8 911.8 919.4 930.9 959.7 30425.4 31025.5 31056.8 31058.2 31648.5 981.4 1085.6 1087.5 1088.6 1102.0 15694.3 16293.9 16426.3 16427.4 16472.6 484347.0 485740.0 486338.0 489713.0 490035.0 59522.7 59967.4 60127.4 60133.7 60222.7 1360.0 1382.7 1391.8 1393.0 1401.2 27526.4 29382.7 29975.0 30140.7 30709.4 45540.3 50385.5 50543.0 50834.7 51021.5 413906.0 415615.0 416540.0 416702.0 419824.0 25368.4 25902.9 26017.8 26533.2 27391.3 41917.0 42301.8 42879.4 43582.3 43918.8 35899.9 35948.8 36027.6 36033.8 36135.8 35292.5 35624.1 35924.5 35936.0 35964.3 35484.5 35542.3 35732.5 35792.0 36593.3 30352.0 32534.5 32650.7 32890.0 32953.0 22887.9 22933.7 23154.9 23257.3 23505.9 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#2] jquery.html Cycles:180 Average:26018.51 Median:20740.58 stddev:71080.22 (342.7%) stddev-sans-first:71253.58 23:30:34 INFO - PROCESS | 26676 | Values: 394.7 398.1 404.3 410.8 422.8 17388.0 18264.6 18462.9 18726.0 18819.3 17937.0 17997.8 18034.6 18136.7 18189.7 5552.4 5580.2 5666.9 5720.0 5757.4 5637.3 5661.0 5690.5 5697.4 5704.2 5448.3 5524.4 5636.9 5647.3 5661.9 5639.4 5667.2 5668.3 5674.8 5686.7 5045.8 5114.5 5236.3 5275.7 5355.0 13106.2 13411.5 13420.3 13495.2 13546.5 2515.7 2530.0 2601.9 2628.4 2630.9 9453.7 9780.2 10070.4 10195.8 10333.9 1927.2 1929.5 1950.1 1950.7 1953.4 69917.7 76272.6 77272.0 77856.6 78374.6 10512.8 10705.8 10865.8 10935.6 10988.0 14820.7 14828.4 14854.1 14862.4 14864.2 7415.2 7564.7 7645.4 7833.5 7850.0 6995.7 7051.2 7152.2 7315.0 7369.2 4580.2 4686.3 4713.4 4720.4 4816.4 1947.8 1990.6 2007.7 2023.7 2065.2 855.4 855.7 859.1 861.7 888.8 17052.7 17133.6 17512.0 17748.3 18159.5 856.2 865.0 866.9 885.6 887.2 9495.9 9904.1 9943.9 10043.1 10151.0 424393.0 430925.0 433032.0 433807.0 434055.0 77103.6 77886.0 78192.6 78432.0 81006.6 982.1 992.4 1014.0 1033.9 1034.7 14750.7 15518.1 16168.9 16276.9 16529.3 39936.6 40409.4 40789.0 40973.8 41070.4 48649.0 48926.5 52271.2 52475.5 54673.5 8042.1 8146.3 8293.6 8341.4 8418.4 10502.4 12108.6 12455.8 12604.8 12736.9 10854.8 10909.8 11321.2 11414.1 11663.2 10629.1 10736.4 10871.6 11156.9 11289.4 10957.8 11065.1 11268.0 11380.5 11437.8 8141.6 8151.9 8229.2 8258.0 8730.2 10574.7 11255.8 11277.0 11278.5 11650.9 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#3] mootools.html Cycles:180 Average:5582.79 Median:5103.99 stddev:3072.70 (60.2%) stddev-sans-first:3064.99 23:30:34 INFO - PROCESS | 26676 | Values: 1368.8 1389.2 1390.1 1390.1 1397.5 7609.6 7799.1 8296.3 8686.7 8691.4 8148.7 8393.3 8398.0 8617.4 8650.6 2336.6 2388.9 2395.4 2396.3 2397.9 2327.3 2393.8 2396.6 2410.3 2410.3 2314.9 2321.1 2338.1 2343.9 2350.4 2278.5 2282.6 2307.6 2310.4 2310.7 2256.4 2324.9 2336.2 2340.4 2359.4 8748.6 8808.1 8819.4 8828.8 8835.0 5263.4 5408.6 5426.5 5430.6 5430.8 3455.2 3471.7 3559.1 3707.8 3750.3 1425.6 1451.6 1460.7 1492.2 1504.2 11279.9 11321.7 11421.7 11495.3 11532.9 4437.4 4462.4 4528.3 4580.9 4593.4 9155.1 9199.4 9201.6 9220.4 9229.9 5145.7 5195.4 5225.5 5245.0 5276.4 4817.3 4886.1 4962.2 4999.0 5057.6 3704.5 3768.5 3791.5 3841.9 3922.7 1166.1 1167.7 1170.1 1183.8 1188.5 958.9 979.6 990.6 997.6 1004.9 5629.9 5793.5 5892.9 5954.9 5979.5 580.5 582.6 582.8 587.5 587.8 3931.5 4030.4 4051.2 4114.8 4156.6 10157.2 10531.1 10549.4 10551.6 10581.9 10335.7 10425.3 10450.5 10450.7 10526.5 7776.7 7863.2 8009.3 8013.2 8047.6 5755.4 5797.9 5842.7 5843.3 5856.7 9086.9 9167.9 9266.1 9361.4 9427.2 9634.4 9646.8 9731.8 9733.8 9739.2 6495.6 6647.6 6955.2 7073.7 7177.0 7933.9 8265.2 8267.2 8268.5 8282.4 7460.3 7466.2 7564.5 7607.8 7661.6 7429.7 7443.6 7468.6 7471.9 7474.3 6461.1 6578.9 6582.9 6589.6 6594.4 4857.6 4893.1 4910.2 4972.2 5062.2 4890.5 4900.1 5035.4 5040.5 5059.2 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#4] prototype.html Cycles:180 Average:4679.27 Median:5355.87 stddev:1360.76 (25.4%) stddev-sans-first:1325.79 23:30:34 INFO - PROCESS | 26676 | Values: 381.5 396.0 406.9 408.5 409.0 5258.3 5323.5 5369.4 5438.0 5444.0 5278.4 5281.1 5348.2 5351.9 5359.9 4141.6 4158.9 4162.3 4199.6 4207.6 4010.3 4099.3 4187.1 4195.3 4214.5 4106.4 4111.6 4125.6 4128.7 4138.5 4122.9 4127.5 4130.1 4139.3 4143.2 3670.1 3684.9 3690.9 3709.1 3710.9 4937.4 5092.3 5143.6 5192.6 5286.3 4740.7 4750.0 4764.8 4767.0 4782.8 5532.7 5537.2 5553.9 5554.8 5582.4 3944.3 3989.1 4031.8 4036.6 4048.0 5727.7 5780.1 5815.6 5824.9 5828.7 4124.7 4126.6 4131.7 4140.0 4144.7 4054.6 4164.2 4182.3 4200.8 4211.1 4716.3 4717.6 4727.8 4738.7 4749.3 5565.9 5572.7 5587.6 5608.8 5624.3 1369.3 1398.3 1410.5 1412.7 1416.4 959.3 976.7 986.6 988.2 992.2 5185.7 5243.7 5245.1 5249.4 5266.2 5478.7 5484.0 5503.9 5513.0 5531.6 5111.6 5221.0 5227.5 5287.5 5418.0 2968.4 2969.2 2974.9 2983.7 2990.4 5803.4 5816.9 5869.0 5884.7 5918.6 5466.9 5634.9 5654.3 5706.8 5721.3 5799.5 5826.6 5850.7 5857.5 5898.8 5300.0 5542.9 5552.3 5561.9 5695.0 5514.3 5532.7 5535.8 5546.6 5563.6 5849.2 5864.6 5884.9 5949.8 5967.6 3904.6 3978.7 4014.0 4016.7 4031.2 5551.8 5583.5 5624.9 5637.6 5667.9 5571.3 5635.8 5678.2 5692.2 5713.3 5593.8 5604.5 5626.9 5637.6 5637.7 5486.9 5492.9 5537.1 5590.4 5618.5 5609.4 5631.1 5660.6 5663.6 5674.9 5388.0 5470.7 5518.9 5535.2 5546.9 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - PROCESS | 26676 | [#5] yui.html Cycles:180 Average:17955.76 Median:24717.09 stddev:23015.57 (93.1%) stddev-sans-first:23043.82 23:30:34 INFO - PROCESS | 26676 | Values: 738.7 746.6 766.7 819.1 858.5 15176.2 16611.6 17812.9 18746.1 18855.9 15390.7 15531.7 15626.8 16789.0 17017.6 788.1 814.6 819.9 825.9 828.8 786.2 806.8 812.8 818.4 848.4 873.7 877.4 880.8 881.5 881.7 867.2 868.6 874.2 874.8 876.1 635.5 638.9 641.4 641.8 642.0 15391.2 15792.8 16306.3 16345.3 16369.3 14421.0 14638.6 14686.8 15004.1 15165.4 12488.1 12529.7 12533.5 12611.0 12652.6 3789.8 3789.9 3794.4 3833.6 3833.9 76031.6 76929.0 78111.6 78994.0 80417.6 11279.6 11310.2 11318.5 11449.6 11659.2 21255.7 21346.5 21367.0 21480.6 21494.5 7959.0 7988.1 8056.2 8104.7 8428.2 4374.2 4395.4 4407.1 4421.9 4439.4 3716.4 3721.3 3752.3 3761.1 3812.7 644.3 647.6 650.5 653.6 665.8 704.5 708.6 726.3 734.4 754.0 10031.0 10275.7 10334.9 10534.3 10542.9 717.9 718.0 734.5 739.7 743.2 4657.7 4963.7 5003.6 5014.3 5027.1 78973.6 78974.0 82401.0 82918.6 84000.6 64650.0 72160.0 72949.3 73435.6 73994.0 63311.1 64937.7 65218.7 65545.0 65834.7 9464.0 9628.8 9661.2 9680.6 9791.0 43793.6 43794.0 44994.8 45014.0 47184.0 55967.0 56594.2 56747.4 57220.1 58441.4 11986.9 12016.9 12017.5 12105.9 12114.4 13656.6 13709.8 14071.2 14150.8 14372.4 12294.4 12764.9 13156.1 13300.4 13745.5 13239.8 13437.1 13568.1 13718.7 14013.9 12764.7 12873.5 12956.5 13422.7 13424.5 10842.8 10850.5 10923.4 11151.8 11600.2 9708.9 10061.9 10125.7 10156.3 10170.0 23:30:34 INFO - PROCESS | 26676 | -------- Summary: end -------- 23:30:34 INFO - PROCESS | 26676 | 23:30:34 INFO - TEST-INFO | 26676: exit 0 23:30:34 INFO - TEST-OK | dromaeo_css | took 1114161ms 23:30:34 INFO - TEST-START | kraken 23:30:34 INFO - operating with platform_type : linux_ 23:30:34 INFO - Initialising browser for kraken test... 23:30:34 INFO - TEST-INFO | started process 27033 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7NxSvu/profile http://localhost:54274/getInfo.html) 23:30:35 INFO - PROCESS | 27033 | 1482651035332 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 23:30:39 INFO - PROCESS | 27033 | __metrics Screen width/height:1600/1200 23:30:39 INFO - PROCESS | 27033 | colorDepth:24 23:30:39 INFO - PROCESS | 27033 | Browser inner width/height: 1024/697 23:30:39 INFO - PROCESS | 27033 | __metrics 23:30:40 ERROR - PROCESS | 27033 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 23:30:40 INFO - TEST-INFO | 27033: exit 0 23:30:40 INFO - Browser initialized. 23:30:40 INFO - Running cycle 1/1 for kraken test... 23:30:40 INFO - TEST-INFO | started process 27157 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7NxSvu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:30:52 INFO - PROCESS | 27157 | RSS: Main: 154357760 23:30:52 INFO - PROCESS | 27157 | 23:30:55 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-beat-detection) 23:30:56 INFO - PROCESS | 27157 | RSS: Main: 161251328 23:30:56 INFO - PROCESS | 27157 | 23:30:59 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-dft) 23:30:59 INFO - PROCESS | 27157 | RSS: Main: 159903744 23:30:59 INFO - PROCESS | 27157 | 23:31:02 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-fft) 23:31:03 INFO - PROCESS | 27157 | RSS: Main: 156532736 23:31:03 INFO - PROCESS | 27157 | 23:31:05 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54274/tests/kraken/driver.html?testName=audio-oscillator) 23:31:05 INFO - PROCESS | 27157 | RSS: Main: 157102080 23:31:05 INFO - PROCESS | 27157 | 23:31:07 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:31:08 INFO - PROCESS | 27157 | RSS: Main: 157577216 23:31:08 INFO - PROCESS | 27157 | 23:31:11 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-darkroom) 23:31:12 INFO - PROCESS | 27157 | RSS: Main: 160776192 23:31:12 INFO - PROCESS | 27157 | 23:31:15 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54274/tests/kraken/driver.html?testName=imaging-desaturate) 23:31:16 INFO - PROCESS | 27157 | RSS: Main: 159588352 23:31:16 INFO - PROCESS | 27157 | 23:31:19 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54274/tests/kraken/driver.html?testName=json-parse-financial) 23:31:19 INFO - PROCESS | 27157 | RSS: Main: 159141888 23:31:19 INFO - PROCESS | 27157 | 23:31:21 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:31:22 INFO - PROCESS | 27157 | RSS: Main: 160186368 23:31:22 INFO - PROCESS | 27157 | 23:31:23 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:31:24 INFO - PROCESS | 27157 | RSS: Main: 159965184 23:31:24 INFO - PROCESS | 27157 | 23:31:26 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:31:27 INFO - PROCESS | 27157 | RSS: Main: 160477184 23:31:27 INFO - PROCESS | 27157 | 23:31:29 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:31:30 INFO - PROCESS | 27157 | RSS: Main: 159760384 23:31:30 INFO - PROCESS | 27157 | 23:31:33 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:31:34 INFO - PROCESS | 27157 | RSS: Main: 160067584 23:31:34 INFO - PROCESS | 27157 | 23:31:35 INFO - PROCESS | 27157 | Cycle 1(1): loaded http://localhost:54274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54274/tests/kraken/driver.html?testName=ai-astar) 23:31:36 INFO - PROCESS | 27157 | RSS: Main: 162095104 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | __start_tp_report 23:31:36 INFO - PROCESS | 27157 | _x_x_mozilla_page_load 23:31:36 INFO - PROCESS | 27157 | _x_x_mozilla_page_load_details 23:31:36 INFO - PROCESS | 27157 | |i|pagename|runs| 23:31:36 INFO - PROCESS | 27157 | |0;ai-astar;105;113;112;111;112;112;112;110;111;113 23:31:36 INFO - PROCESS | 27157 | |1;audio-beat-detection;148;123;125;171;122;125;125;124;139;122 23:31:36 INFO - PROCESS | 27157 | |2;audio-dft;162;162;161;163;165;171;164;163;162;164 23:31:36 INFO - PROCESS | 27157 | |3;audio-fft;76;78;78;115;75;76;77;76;92;76 23:31:36 INFO - PROCESS | 27157 | |4;audio-oscillator;85;83;112;85;85;84;98;84;84;114 23:31:36 INFO - PROCESS | 27157 | |5;imaging-gaussian-blur;119;118;117;117;118;120;119;117;120;121 23:31:36 INFO - PROCESS | 27157 | |6;imaging-darkroom;148;149;150;149;148;149;147;148;148;148 23:31:36 INFO - PROCESS | 27157 | |7;imaging-desaturate;96;97;96;97;96;97;98;96;96;96 23:31:36 INFO - PROCESS | 27157 | |8;json-parse-financial;57;59;56;59;58;57;58;59;58;59 23:31:36 INFO - PROCESS | 27157 | |9;json-stringify-tinderbox;68;66;67;66;66;67;67;67;66;68 23:31:36 INFO - PROCESS | 27157 | |10;stanford-crypto-aes;83;82;83;82;88;85;87;84;82;81 23:31:36 INFO - PROCESS | 27157 | |11;stanford-crypto-ccm;139;126;128;127;141;138;133;130;186;132 23:31:36 INFO - PROCESS | 27157 | |12;stanford-crypto-pbkdf2;173;168;171;164;169;171;172;170;168;167 23:31:36 INFO - PROCESS | 27157 | |13;stanford-crypto-sha256-iterative;63;66;64;69;64;63;70;63;64;61 23:31:36 INFO - PROCESS | 27157 | __end_tp_report 23:31:36 INFO - PROCESS | 27157 | __start_cc_report 23:31:36 INFO - PROCESS | 27157 | _x_x_mozilla_cycle_collect,742 23:31:36 INFO - PROCESS | 27157 | __end_cc_report 23:31:36 INFO - PROCESS | 27157 | __startTimestamp1482651096168__endTimestamp 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | ------- Summary: start ------- 23:31:36 INFO - PROCESS | 27157 | Number of tests: 14 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.33 (2.1%) stddev-sans-first:0.97 23:31:36 INFO - PROCESS | 27157 | Values: 105.0 113.0 112.0 111.0 112.0 112.0 112.0 110.0 111.0 113.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:125.00 stddev:16.04 (12.8%) stddev-sans-first:15.99 23:31:36 INFO - PROCESS | 27157 | Values: 148.0 123.0 125.0 171.0 122.0 125.0 125.0 124.0 139.0 122.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.50 stddev:2.83 (1.7%) stddev-sans-first:2.93 23:31:36 INFO - PROCESS | 27157 | Values: 162.0 162.0 161.0 163.0 165.0 171.0 164.0 163.0 162.0 164.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.50 stddev:12.64 (16.5%) stddev-sans-first:13.23 23:31:36 INFO - PROCESS | 27157 | Values: 76.0 78.0 78.0 115.0 75.0 76.0 77.0 76.0 92.0 76.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:84.50 stddev:12.19 (14.4%) stddev-sans-first:12.70 23:31:36 INFO - PROCESS | 27157 | Values: 85.0 83.0 112.0 85.0 85.0 84.0 98.0 84.0 84.0 114.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#5] imaging-gaussian-blur Cycles:10 Average:118.60 Median:119.00 stddev:1.43 (1.2%) stddev-sans-first:1.51 23:31:36 INFO - PROCESS | 27157 | Values: 119.0 118.0 117.0 117.0 118.0 120.0 119.0 117.0 120.0 121.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 23:31:36 INFO - PROCESS | 27157 | Values: 148.0 149.0 150.0 149.0 148.0 149.0 147.0 148.0 148.0 148.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:96.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 23:31:36 INFO - PROCESS | 27157 | Values: 96.0 97.0 96.0 97.0 96.0 97.0 98.0 96.0 96.0 96.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.50 stddev:1.05 (1.8%) stddev-sans-first:1.05 23:31:36 INFO - PROCESS | 27157 | Values: 57.0 59.0 56.0 59.0 58.0 57.0 58.0 59.0 58.0 59.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.71 23:31:36 INFO - PROCESS | 27157 | Values: 68.0 66.0 67.0 66.0 66.0 67.0 67.0 67.0 66.0 68.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:83.50 stddev:2.31 (2.8%) stddev-sans-first:2.44 23:31:36 INFO - PROCESS | 27157 | Values: 83.0 82.0 83.0 82.0 88.0 85.0 87.0 84.0 82.0 81.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:135.50 stddev:17.65 (13.0%) stddev-sans-first:18.72 23:31:36 INFO - PROCESS | 27157 | Values: 139.0 126.0 128.0 127.0 141.0 138.0 133.0 130.0 186.0 132.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:170.50 stddev:2.67 (1.6%) stddev-sans-first:2.47 23:31:36 INFO - PROCESS | 27157 | Values: 173.0 168.0 171.0 164.0 169.0 171.0 172.0 170.0 168.0 167.0 23:31:36 INFO - PROCESS | 27157 | 23:31:36 INFO - PROCESS | 27157 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.00 stddev:2.83 (4.4%) stddev-sans-first:2.93 23:31:36 INFO - PROCESS | 27157 | Values: 63.0 66.0 64.0 69.0 64.0 63.0 70.0 63.0 64.0 61.0 23:31:36 INFO - PROCESS | 27157 | -------- Summary: end -------- 23:31:36 INFO - PROCESS | 27157 | 23:31:37 INFO - TEST-INFO | 27157: exit 0 23:31:37 INFO - TEST-OK | kraken | took 62407ms 23:31:37 INFO - SUITE-END | took 1176s 23:31:37 INFO - Completed test suite (00:19:36) 23:31:37 INFO - javascript benchmark 23:31:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7062.7836642586335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3169.302753394253, 3172.762042846787, 3191.2341038150835, 3210.8555115019813, 3215.646278909318, 5208.348956380452, 5318.441563635821, 5319.441458646845, 5375.543078838298, 5409.134538473845, 5243.79024839007, 5297.258383826267, 5316.893662126755, 5354.625176237665, 5454.963556924192, 4817.951820481803, 4858.4898585648525, 4891.021795640868, 4971.726555039471, 4986.276989836471, 4770.761461926907, 4858.368412106439, 4862.78117484714, 4883.731394773286, 4897.559219670229, 4646.1404640141345, 4863.513648635108, 4876.21980483121, 4902.583280421169, 4973.403191617011, 4644.907101857978, 4840.177169881129, 4874.1713908635265, 4894.412670479565, 4968.031233909389, 4870.9269360959615, 4871.732054736988, 4879.048585525824, 4905.313256144107, 4931.358923339979, 5348.919766203471, 5354.330708661418, 5391.919121213145, 5414.052540805383, 5418.9458105418835, 5596.132599447093, 5597.776088956478, 5737.827865164052, 5757.539396848284, 5768.653880767168, 5860.120981852713, 5865.384134665841, 5867.76528938842, 5879.265091863517, 5921.496672782819, 5397.649152805055, 5421.918671219935, 5426.430224826416, 5429.4299098594865, 5429.782808687608, 5704.0, 5728.513076388554, 5813.128030795415, 5830.125481177773, 5955.225820643375, 5656.943430565724, 5657.9434205658235, 5669.31968163823, 5699.515541178963, 5736.340320863071, 5690.430956904359, 5716.857078573069, 5739.512141467939, 5749.88500229993, 5770.105633626795, 5475.206095116186, 5521.4478552145265, 5684.886302274014, 5732.885342293131, 5737.3688894221605, 5755.683437410982, 5798.275215598051, 5819.912701309484, 5822.563307751935, 5848.736806843703, 4324.17840610283, 4332.653387728974, 4332.783360831946, 4341.0666706657585, 4371.803268852909, 3753.8123093845197, 3779.603141670085, 3802.4866643002842, 3831.9616803832164, 3890.124721937598, 5462.590305727086, 5473.261109750133, 5506.91739623906, 5511.3937466878615, 5531.9723401382735, 5701.71491425427, 5717.885642287131, 5726.77092916287, 5737.3688894221605, 5758.539316854642, 5337.466253374631, 5341.065313570188, 5404.351477822608, 5478.616496845262, 5488.231647569343, 4758.881027974328, 4779.569838714532, 4784.593309568726, 4800.807967681323, 4828.348172996601, 6040.335563088057, 6044.455998960027, 6046.818595442144, 6049.697515124226, 6050.576459647782, 5572.4148964358155, 5790.623609465371, 5808.5353171747, 5815.447532484407, 5822.592418530662, 5818.0, 5897.764089436546, 5920.467157955804, 5928.407159284037, 6005.219321488354, 5558.0, 5603.607747457639, 5610.298712660918, 5612.579056570692, 5622.971885140548, 5690.260266165372, 5694.715264236855, 5716.771329146787, 5746.511546518593, 5749.741261643319, 5469.2616496772425, 5500.7524661391135, 5506.174073888948, 5555.111182210827, 5565.526930210977, 5537.280153580009, 5567.526760225426, 5578.748956297058, 5580.35825880029, 5581.358143813513, 5533.972330138324, 5537.501624853782, 5539.833804985857, 5545.7781688732, 5569.637973531708, 5706.600537962303, 5739.8852022960145, 5741.712914354182, 5755.0, 5787.536997040311, 5586.636868603528, 5596.832095037155, 5610.579206559442, 5620.409856965123, 5633.380328163817], "value": 5287.149883926083, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.3609150973077, 548.1585765849419, 549.7636016512903, 568.3549171690133, 571.7570032736086, 40381.394279085835, 43042.35436468456, 43192.352114718306, 46499.535004649944, 46526.53473465273, 44476.110477790506, 44553.0, 44803.55196448034, 44820.32769508452, 44985.0, 8918.197362237397, 9044.909550904473, 9063.093690630918, 9087.363884528086, 10058.195344372469, 9995.2003839693, 10401.583936642524, 10411.2191585631, 10412.479376031206, 10453.0, 10472.2145839062, 10479.790404191912, 10521.368717876914, 10541.417352748587, 10548.0, 10438.843417348746, 10485.056344928993, 10489.370637761758, 10507.527161277761, 10516.579336826519, 10198.949005255, 10257.230707696877, 10264.64073757415, 10276.177905767521, 10312.845307320396, 37883.43174852379, 37943.0, 37964.62035379666, 38394.0, 38589.0, 4127.607877251657, 4156.750594964312, 4225.577442255781, 4238.957610423887, 4262.595053469915, 28902.566461503095, 29107.417851642847, 29249.707502925125, 29911.252218694488, 30527.0, 4212.599803018708, 4258.659307255412, 4332.1985432695055, 4360.4331436913, 4392.297232442826, 302128.0, 304100.0, 304343.0, 305387.0, 305809.0, 45419.54580454219, 45665.0, 45730.54269457329, 46623.53376466192, 47180.0, 55132.44867551354, 55352.44647553554, 55668.72165639227, 55756.0, 55820.0, 14790.0, 15318.770218446733, 15356.464353564554, 15375.154366509949, 15429.38282468711, 10581.470926453647, 10706.357618542912, 11356.829647555292, 11528.0, 11550.826737598944, 17128.40050598248, 17212.225449854788, 17263.654726905643, 17274.481765547054, 17301.3944511944, 6573.704183311763, 6789.6605169741315, 6866.725330986705, 6898.310168983062, 6907.13660792401, 895.7626229049307, 911.7720569857536, 919.4069824962995, 930.9255259579218, 959.6737109382843, 30425.391492170034, 31025.534616980764, 31056.844715776275, 31058.223544411572, 31648.525272120936, 981.3670182732097, 1085.6200329884634, 1087.5106202209038, 1088.6244245735368, 1101.961431349899, 15694.293756780973, 16293.918530407273, 16426.342946282253, 16427.425040123537, 16472.588185295463, 484347.0, 485740.0, 486338.0, 489713.0, 490035.0, 59522.70238648866, 59967.40032599618, 60127.39872601218, 60133.69933150306, 60222.698886505284, 1359.9932000340134, 1382.7303675783126, 1391.7912313153108, 1393.0457636519018, 1401.2153194211273, 27526.449471010867, 29382.706172937997, 29975.0, 30140.698593014666, 30709.38581228318, 45540.31689524726, 50385.49614503955, 50543.0, 50834.745826270635, 51021.48978510167, 413906.0, 415615.0, 416540.0, 416702.0, 419824.0, 25368.365790855376, 25902.87048564745, 26017.83714605654, 26533.204003879913, 27391.315217119733, 41917.0, 42301.78849105735, 42879.3568096485, 43582.34626480542, 43918.780406097765, 35899.92300230997, 35948.82025589855, 36027.63972360243, 36033.81983090068, 36135.819320903225, 35292.47061294134, 35624.109397265274, 35924.4611330825, 35936.0, 35964.28071438609, 35484.46773298351, 35542.28915421729, 35732.46401304034, 35792.0, 36593.26813463769, 30352.0, 32534.511982320757, 32650.673493265713, 32890.0, 32953.0, 22887.885560572755, 22933.655995159752, 23154.885396917132, 23257.3022809316, 23505.882470587538], "value": 19267.841728359406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.66847847807855, 398.1221406798011, 404.3227593780417, 410.772021528052, 422.8393210579979, 17388.0, 18264.634707305846, 18462.90768546159, 18726.0, 18819.34132305368, 17937.0, 17997.820021799776, 18034.63930721388, 18136.727949080774, 18189.7271540927, 5552.361478429982, 5580.218769372291, 5666.941680500203, 5720.0, 5757.42425757423, 5637.267155269829, 5661.037623603997, 5690.516306113965, 5697.401772813857, 5704.20141180233, 5448.346198456171, 5524.4199359067325, 5636.915446268309, 5647.265855438787, 5661.886762264773, 5639.407862174474, 5667.206591077232, 5668.348139963895, 5674.801381951653, 5686.715664216814, 5045.772940217699, 5114.514121158484, 5236.293100431431, 5275.709835959021, 5354.973225133888, 13106.2136271824, 13411.530596429177, 13420.261885596288, 13495.190288582717, 13546.45814167432, 2515.74202462067, 2529.9747002530107, 2601.856897870616, 2628.4348864993926, 2630.8947642094486, 9453.723747294096, 9780.168685661727, 10070.395776253448, 10195.79608407835, 10333.948330258376, 1927.2194761121768, 1929.4693959161343, 1950.1419375474745, 1950.697641865513, 1953.4237399967044, 69917.65041174812, 76272.61863690702, 77272.0, 77856.61071694661, 78374.608126959, 10512.842307365396, 10705.785884282272, 10865.782684346268, 10935.617253396093, 10988.0, 14820.703585928437, 14828.406863725546, 14854.108753474826, 14862.405503779728, 14864.18246996404, 7415.1843297237265, 7564.735234266775, 7645.426593005435, 7833.454127408615, 7849.960750196213, 6995.720171193197, 7051.224365319811, 7152.213256541776, 7314.96342518284, 7369.189389167187, 4580.244259697112, 4686.250199967989, 4713.363695901009, 4720.409948756405, 4816.373871396766, 1947.7565304336958, 1990.6416844967755, 2007.7289565908416, 2023.6660950943224, 2065.173930427841, 855.4012191465998, 855.7475544714324, 859.0936561927102, 861.7414775567305, 888.760034790603, 17052.65894682124, 17133.57166070858, 17512.0, 17748.29006839712, 18159.45521634353, 856.2037305306125, 865.0, 866.8829707989466, 885.6191837509804, 887.1970866365949, 9495.857562136573, 9904.108630223314, 9943.900560994442, 10043.146332561813, 10151.0, 424393.0, 430925.0, 433032.0, 433807.0, 434055.0, 77103.61448192835, 77886.0, 78192.60903695445, 78432.0, 81006.59496702597, 982.0915653020927, 992.3847214726771, 1014.0417305646093, 1033.9121174700235, 1034.730969947804, 14750.704985900436, 15518.068915865084, 16168.919155404148, 16276.918615406847, 16529.33882644681, 39936.60063399329, 40409.39385909273, 40789.0, 40973.795131024155, 41070.38394424026, 48649.0, 48926.510734892196, 52271.21593176181, 52475.47524524706, 54673.45326546684, 8042.115367309708, 8146.266835984789, 8293.58532073406, 8341.41610087288, 8418.368622353226, 10502.369857808557, 12108.636740897788, 12455.813162802746, 12604.810927836274, 12736.936315318364, 10854.782904342026, 10909.781804363709, 11321.150913681671, 11414.14393920476, 11663.183577149857, 10629.096526795309, 10736.355818650905, 10871.565137394417, 11156.944215279196, 11289.379084150121, 10957.83563246536, 11065.114790816877, 11268.0, 11380.531856508573, 11437.771244575228, 8141.59292035384, 8151.918480815353, 8229.177082291963, 8258.0, 8730.214280714512, 10574.735631609272, 11255.774884502427, 11277.041451476389, 11278.49246783913, 11650.941745291559], "value": 8664.54369597559, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.7809950407936, 1389.1595584671275, 1390.10338331776, 1390.131168019988, 1397.5318268380095, 7609.581473018981, 7799.10310314314, 8296.29481494073, 8686.695965641204, 8691.391602587813, 8148.7147949821765, 8393.328533717304, 8397.958010209955, 8617.396782225247, 8650.56747162641, 2336.6495025746103, 2388.9283321500297, 2395.401149712572, 2396.3290278721984, 2397.9280621581293, 2327.290176496168, 2393.772591603795, 2396.6405039244164, 2410.2889647553925, 2410.349205714465, 2314.918977835776, 2321.129576408847, 2338.1348900906687, 2343.894524746382, 2350.3654013416367, 2278.4873403484085, 2282.6347784354507, 2307.5846347657416, 2310.3992961829877, 2310.7111611048617, 2256.3907744908784, 2324.8837558122027, 2336.158982766203, 2340.379799353173, 2359.433735903388, 8748.60631271588, 8808.075152109064, 8819.426737262058, 8828.779280517974, 8834.955825220897, 5263.368395792491, 5408.59435542332, 5426.484483974016, 5430.5927055471, 5430.809921652763, 3455.2053027803745, 3471.687548120656, 3559.056849934769, 3707.7589956653, 3750.306193354238, 1425.6150839273441, 1451.637090727318, 1460.6640472691233, 1492.1812090544936, 1504.2027725305607, 11279.943600282028, 11321.716957076056, 11421.714457138554, 11495.2528085675, 11532.94233528827, 4437.400950871655, 4462.44219472566, 4528.343390208402, 4580.862574122782, 4593.402857628487, 9155.084491550924, 9199.356045076915, 9201.631934722536, 9220.400673956305, 9229.861552076723, 5145.742712864342, 5195.350581177353, 5225.451327610622, 5245.029669511153, 5276.39321478035, 4817.27740838877, 4886.120498310267, 4962.230854217602, 4999.0, 5057.620678449131, 3704.49989251453, 3768.491253680718, 3791.545014598266, 3841.8655347063277, 3922.686185105185, 1166.1021013819482, 1167.737259116692, 1170.0814860334597, 1183.7632473505334, 1188.5424111716877, 958.8705524754208, 979.5934687104772, 990.6235630460379, 997.6308765756571, 1004.8995100490039, 5629.943700563023, 5793.478586927113, 5892.882142357214, 5954.880902382015, 5979.491743201789, 580.5152697497552, 582.6009183709173, 582.7639805878626, 587.5094296262583, 587.8207146820175, 3931.5282166140246, 4030.435738996543, 4051.1897620476025, 4114.753114813121, 4156.625903668684, 10157.238207134495, 10531.104856087166, 10549.419781911914, 10551.630692925712, 10581.947090264654, 10335.689929302132, 10425.322354046963, 10450.477476126163, 10450.738731531772, 10526.473676316153, 7776.650050747843, 7863.174366691529, 8009.31920786728, 8013.198680132056, 8047.637856296364, 5755.424457554379, 5797.8550536236935, 5842.737076831468, 5843.328017277898, 5856.677882716492, 9086.863697044682, 9167.908320916706, 9266.11971862672, 9361.4383137012, 9427.245820334494, 9634.421934683942, 9646.758831029, 9731.756706082122, 9733.756656083371, 9739.172170365315, 6495.642739649365, 6647.634380108947, 6955.1653801544135, 7073.6816843243205, 7177.031100801325, 7933.880991785243, 8265.173482651686, 8267.173282671685, 8268.545230012407, 8282.378821588285, 7460.328570428687, 7466.216047315171, 7564.50830696003, 7607.809804754704, 7661.578613176329, 7429.7399591014055, 7443.55338679681, 7468.589227592317, 7471.8879216812875, 7474.289942455458, 6461.127747753993, 6578.901316480161, 6582.868342633216, 6589.6375699336995, 6594.406503414716, 4857.635677324144, 4893.09477746618, 4910.165271903714, 4972.179590367549, 5062.2406639002975, 4890.462049174517, 4900.117978763857, 5035.420926593342, 5040.546350828443, 5059.215821547666], "value": 4488.164223125619, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.4583291725749, 395.97228194026417, 406.90030942419105, 408.51590864825164, 408.9795510224488, 5258.342707161605, 5323.520883120524, 5369.355677318722, 5437.999999999995, 5443.972780136104, 5278.392984806748, 5281.128613778722, 5348.197770334452, 5351.866203344918, 5359.866003349918, 4141.62725354718, 4158.858188033166, 4162.313218318989, 4199.580041995807, 4207.579242075783, 4010.3382941814566, 4099.3031184698675, 4187.120704652027, 4195.349720793282, 4214.536400995888, 4106.445629839978, 4111.60939710727, 4125.64931980782, 4128.69034822388, 4138.482689663792, 4122.876313710563, 4127.504699436087, 4130.091379896418, 4139.254934111858, 4143.192077544895, 3670.100825297819, 3684.9180213179466, 3690.8708195213308, 3709.0727318170457, 3710.90333961311, 4937.432195297551, 5092.261622064817, 5143.562797162245, 5192.636515443921, 5286.259923610659, 4740.7200055984695, 4749.976250118727, 4764.809407623691, 4767.0, 4782.760861956923, 5532.723363831792, 5537.169424586304, 5553.861153471155, 5554.805581804658, 5582.358028826697, 3944.270309992632, 3989.142334398089, 4031.778252196128, 4036.5761595032686, 4047.9797601011805, 5727.684977326246, 5780.132980052984, 5815.621984571032, 5824.912626310609, 5828.6502809831545, 4124.711270211116, 4126.587341265849, 4131.690123240709, 4139.97930010348, 4144.730592511477, 4054.56725964419, 4164.187983343219, 4182.2681030820095, 4200.768957707355, 4211.115665710174, 4716.335270572115, 4717.599004084623, 4727.834525791581, 4738.73936933465, 4749.335093086971, 5565.9443405566235, 5572.721363931787, 5587.608867379245, 5608.831735047955, 5624.268845050199, 1369.274284629148, 1398.3427788939182, 1410.4781230944411, 1412.7174565087023, 1416.390951890697, 959.3236768078488, 976.72651657536, 986.6250824686573, 988.229181238635, 992.2260636703382, 5185.740712964337, 5243.737813109406, 5245.1345527988215, 5249.42256351801, 5266.157414813688, 5478.698671573102, 5484.0, 5503.944960550423, 5512.952539017574, 5531.640443371168, 5111.591072714248, 5221.0, 5227.484029631364, 5287.4976877196605, 5417.993013877257, 2968.3618022125347, 2969.2280007198283, 2974.9107526774233, 2983.7165469280385, 2990.401919616042, 5803.361630220672, 5816.88366232673, 5868.970655146697, 5884.676342801102, 5918.585699001029, 5466.890662186734, 5634.887302253932, 5654.349749778746, 5706.771729130788, 5721.313442386941, 5799.52028782727, 5826.592138550261, 5850.707464626752, 5857.47282744555, 5898.8230353089475, 5300.0, 5542.944570554243, 5552.278203833475, 5561.860953476195, 5695.0, 5514.283143191359, 5532.699815543264, 5535.785685725742, 5546.58400619947, 5563.638363506359, 5849.210356601804, 5864.589478736448, 5884.941150588611, 5949.791757288474, 5967.582269241111, 3904.6095390460728, 3978.7015973801504, 4013.9598604013586, 4016.718829681894, 4031.1534577739003, 5551.805686800943, 5583.4595259157795, 5624.915626265692, 5637.590602349413, 5667.874547044858, 5571.303587051619, 5635.802746903839, 5678.217673340117, 5692.203091567267, 5713.3149118818965, 5593.804216852391, 5604.495595396434, 5626.874625075083, 5637.633553818989, 5637.661740295595, 5486.862828429162, 5492.945070549403, 5537.1140617501005, 5590.440955904377, 5618.4662457066515, 5609.411011843861, 5631.128124656373, 5660.632058916157, 5663.612414958362, 5674.943250567441, 5387.973060134675, 5470.72646367688, 5518.862028449321, 5535.169724541351, 5546.889062218814], "value": 4264.595408798942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.6786747764726, 746.6117618838208, 766.6511737159593, 819.1358117186368, 858.5192292316303, 15176.2411879406, 16611.584710382245, 17812.910935445303, 18746.062696865138, 18855.90572047141, 15390.692186156299, 15531.68936621267, 15626.843731562682, 16788.99266044038, 17017.57456063599, 788.113372455987, 814.5764202614637, 819.9057108432518, 825.859603867341, 828.772087675888, 786.2137862137862, 806.7741032510877, 812.7927378518469, 818.3657665309403, 848.367965865428, 873.7335112790596, 877.3595275448926, 880.8018195905934, 881.5195718333524, 881.7002219245455, 867.2411639815161, 868.6134670071821, 874.2001069021837, 874.7681864305964, 876.0538618292229, 635.4598591197491, 638.8818068657313, 641.4066988036049, 641.8427485266093, 642.0497663458061, 15391.230438478144, 15792.763108553381, 16306.347746090141, 16345.34618615254, 16369.345226190939, 14421.0, 14638.634034149125, 14686.779698304534, 15004.099754014795, 15165.393384264617, 12488.066259128345, 12529.74940501185, 12533.498660053587, 12611.0, 12652.557160499431, 3789.829457674411, 3789.9431508527396, 3794.354959656865, 3833.6358045985585, 3833.9233215335817, 76031.61984190099, 76929.0, 78111.60944195243, 78994.0, 80417.59791201007, 11279.616115165418, 11310.208285420105, 11318.547258109747, 11449.59926402572, 11659.183857130089, 21255.68116478254, 21346.466338341666, 21367.0, 21480.57038859214, 21494.462638434165, 7958.960205198937, 7988.121306656263, 8056.194380561897, 8104.675812967415, 8428.199321064489, 4374.234508960919, 4395.428594282786, 4407.140607581555, 4421.911561768747, 4439.400680908101, 3716.4239542870896, 3721.292954338667, 3752.2683076799763, 3761.1161377076364, 3812.7140464465274, 644.3298969072206, 647.5984889368622, 650.5316172355896, 653.5588477777576, 665.8035879415584, 704.5244459989486, 708.6102643546029, 726.2664708644307, 734.3611058379245, 753.9547627142389, 10031.0, 10275.74310642225, 10334.948325258325, 10534.262601617813, 10542.947285263677, 717.8907497198771, 717.989230161548, 734.4565021883767, 739.7189068154174, 743.1676522295064, 4657.743824089707, 4963.7224444422645, 5003.624728145403, 5014.34813474246, 5027.145385284511, 78973.60513197513, 78974.0, 82401.0, 82918.58540707378, 84000.55740250525, 64650.0302495463, 72160.0, 72949.27050729531, 73435.63282183555, 73994.0, 63311.050334245025, 64937.67531162314, 65218.67390663016, 65545.0, 65834.67082664651, 9464.0, 9628.75928101803, 9661.178799802095, 9680.612775488902, 9790.951045245014, 43793.56206437895, 43793.99999999873, 44994.77502612466, 45014.0, 47184.0, 55967.0, 56594.15108773289, 56747.432525674216, 57220.1416978754, 58441.4155858453, 11986.940065299617, 12016.939915300367, 12017.459214335551, 12105.939470302945, 12114.394280285775, 13656.590302290948, 13709.794353084511, 14071.155730656194, 14150.78773818373, 14372.425102995763, 12294.446749896455, 12764.936175319064, 13156.144850584682, 13300.40148193353, 13745.450181992608, 13239.801402979156, 13437.126586771828, 13568.118072325267, 13718.65703357424, 14013.929930350283, 12764.680882978, 12873.549425770054, 12956.481740730265, 13422.664433389244, 13424.463021479032, 10842.837357439486, 10850.457477125954, 10923.399213043358, 11151.776964460827, 11600.187986841178, 9708.854367184356, 10061.899381006097, 10125.746856328651, 10156.289059765746, 10170.0], "value": 7346.236251241971, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 112.0, 111.0, 112.0, 112.0, 112.0, 110.0, 111.0, 113.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 125.0, 171.0, 122.0, 125.0, 125.0, 124.0, 139.0, 122.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 163.0, 165.0, 171.0, 164.0, 163.0, 162.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 78.0, 115.0, 75.0, 76.0, 77.0, 76.0, 92.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 112.0, 85.0, 85.0, 84.0, 98.0, 84.0, 84.0, 114.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 117.0, 118.0, 120.0, 119.0, 117.0, 120.0, 121.0], "value": 118.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 150.0, 149.0, 148.0, 149.0, 147.0, 148.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 98.0, 96.0, 96.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 56.0, 59.0, 58.0, 57.0, 58.0, 59.0, 58.0, 59.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 66.0, 68.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 82.0, 88.0, 85.0, 87.0, 84.0, 82.0, 81.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 126.0, 128.0, 127.0, 141.0, 138.0, 133.0, 130.0, 186.0, 132.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 168.0, 171.0, 164.0, 169.0, 171.0, 172.0, 170.0, 168.0, 167.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 69.0, 64.0, 63.0, 70.0, 63.0, 64.0, 61.0], "value": 64.7, "unit": "score"}]}]} 23:31:37 INFO - Return code: 0 23:31:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:31:37 ERROR - # TBPL SUCCESS # 23:31:37 INFO - Running post-action listener: _resource_record_post_action 23:31:37 INFO - [mozharness: 2016-12-25 07:31:37.503087Z] Finished run-tests step (success) 23:31:37 INFO - Running post-run listener: _resource_record_post_run 23:31:37 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 403243008; Read time: 8; Write time: 331008 23:31:37 INFO - TinderboxPrint: CPU usage
13.4% 23:31:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:31:37 INFO - TinderboxPrint: I/O write bytes / time
403,243,008 / 331,008 23:31:37 INFO - TinderboxPrint: CPU idle
8,245.6 (86.6%) 23:31:37 INFO - TinderboxPrint: CPU user
1,219.1 (12.8%) 23:31:37 INFO - TinderboxPrint: Swap in / out
0 / 0 23:31:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110088192; Read time: 0; Write time: 69020 23:31:37 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 292261888; Read time: 0; Write time: 261860 23:31:38 INFO - Running post-run listener: _upload_blobber_files 23:31:38 INFO - Blob upload gear active. 23:31:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:31:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:31:38 INFO - (blobuploader) - INFO - Open directory for files ... 23:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:31:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:39 INFO - (blobuploader) - INFO - Done attempting. 23:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:31:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:40 INFO - (blobuploader) - INFO - Done attempting. 23:31:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:31:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:41 INFO - (blobuploader) - INFO - Done attempting. 23:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:45 INFO - (blobuploader) - INFO - Done attempting. 23:31:45 INFO - (blobuploader) - INFO - Iteration through files over. 23:31:45 INFO - Return code: 0 23:31:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:31:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:31:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/714f304df5af7c784345cd3ef79224c8ff00980d89a48fd1d26c218ef0cb3ed9201cc3e2f55366d0fca24f6d06672a47322bd9e3af5c0410b8c63aa12c701d84", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/948df5fc67fc0bbb29faa0f28fec574fe2bd2bdac6fe0cc77fd0f410636ad0a4af050b6c4b6be1ae2ade40d3075b40d2e7bf5979c178aa6e9d55e5549ed8ade0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c05db9d4813b41718916e86ac6cec5493cda6643417839c55fa338b3ac4c0c5dbbae2ba83fbfe90f2c845a4e44861c6a6f6d472d35d31c460606cb963e91e3e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d7f1f8b96cd2ae397b2ea063fb1c22034b1f6e38952c053d258eb6cc5d1e0c672ebcbcea82790d5b7741f999011bad879edc5502032484afb7bd08dbdeec3c6"} 23:31:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:31:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:31:45 INFO - Contents: 23:31:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/714f304df5af7c784345cd3ef79224c8ff00980d89a48fd1d26c218ef0cb3ed9201cc3e2f55366d0fca24f6d06672a47322bd9e3af5c0410b8c63aa12c701d84", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/948df5fc67fc0bbb29faa0f28fec574fe2bd2bdac6fe0cc77fd0f410636ad0a4af050b6c4b6be1ae2ade40d3075b40d2e7bf5979c178aa6e9d55e5549ed8ade0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c05db9d4813b41718916e86ac6cec5493cda6643417839c55fa338b3ac4c0c5dbbae2ba83fbfe90f2c845a4e44861c6a6f6d472d35d31c460606cb963e91e3e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d7f1f8b96cd2ae397b2ea063fb1c22034b1f6e38952c053d258eb6cc5d1e0c672ebcbcea82790d5b7741f999011bad879edc5502032484afb7bd08dbdeec3c6"} 23:31:45 INFO - Running post-run listener: copy_logs_to_upload_dir 23:31:45 INFO - Copying logs to upload dir... 23:31:45 INFO - mkdir: /builds/slave/test/build/upload/logs 23:31:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.729592 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-24 23:31:44.829913) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 23:31:44.834312) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 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/714f304df5af7c784345cd3ef79224c8ff00980d89a48fd1d26c218ef0cb3ed9201cc3e2f55366d0fca24f6d06672a47322bd9e3af5c0410b8c63aa12c701d84", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/948df5fc67fc0bbb29faa0f28fec574fe2bd2bdac6fe0cc77fd0f410636ad0a4af050b6c4b6be1ae2ade40d3075b40d2e7bf5979c178aa6e9d55e5549ed8ade0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c05db9d4813b41718916e86ac6cec5493cda6643417839c55fa338b3ac4c0c5dbbae2ba83fbfe90f2c845a4e44861c6a6f6d472d35d31c460606cb963e91e3e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d7f1f8b96cd2ae397b2ea063fb1c22034b1f6e38952c053d258eb6cc5d1e0c672ebcbcea82790d5b7741f999011bad879edc5502032484afb7bd08dbdeec3c6"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011441 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482643811/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/714f304df5af7c784345cd3ef79224c8ff00980d89a48fd1d26c218ef0cb3ed9201cc3e2f55366d0fca24f6d06672a47322bd9e3af5c0410b8c63aa12c701d84", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/948df5fc67fc0bbb29faa0f28fec574fe2bd2bdac6fe0cc77fd0f410636ad0a4af050b6c4b6be1ae2ade40d3075b40d2e7bf5979c178aa6e9d55e5549ed8ade0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c05db9d4813b41718916e86ac6cec5493cda6643417839c55fa338b3ac4c0c5dbbae2ba83fbfe90f2c845a4e44861c6a6f6d472d35d31c460606cb963e91e3e1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3d7f1f8b96cd2ae397b2ea063fb1c22034b1f6e38952c053d258eb6cc5d1e0c672ebcbcea82790d5b7741f999011bad879edc5502032484afb7bd08dbdeec3c6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 23:31:44.863901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:31:44.864227) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1482649804.16113-12170122 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 23:31:44.947575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 23:31:44.947916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 23:31:44.948246) ========= ========= Total master_lag: 0.18 =========