builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1470179940.37 results: success (0) buildid: 20160802161836 builduid: 67b63fe7460a4880835dcff42af618a4 revision: 73a57814a495b29244ef5377e73488a3f3fabb15 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.365921) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.366320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.366593) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.414832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.415748) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 16:19:00-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-08-02 16:19:00 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136238 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.565635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.565940) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019427 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:00.615082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 16:19:00.615456) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 73a57814a495b29244ef5377e73488a3f3fabb15 --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 73a57814a495b29244ef5377e73488a3f3fabb15 --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=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 16:19:00,764 truncating revision to first 12 chars 2016-08-02 16:19:00,764 Setting DEBUG logging. 2016-08-02 16:19:00,764 attempt 1/10 2016-08-02 16:19:00,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/73a57814a495?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 16:19:01,445 unpacking tar archive at: mozilla-inbound-73a57814a495/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045392 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 16:19:01.673933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.674224) ========= script_repo_revision: 73a57814a495b29244ef5377e73488a3f3fabb15 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.674592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.674858) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 16:19:01.686045) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-02 16:19:01.686364) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:19:01 INFO - MultiFileLogger online at 20160802 16:19:01 in /builds/slave/test 16:19:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:19:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:19:01 INFO - {'append_to_log': False, 16:19:01 INFO - 'base_work_dir': '/builds/slave/test', 16:19:01 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:19:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:19:01 INFO - 'branch': 'Mozilla-Inbound', 16:19:01 INFO - 'buildbot_json_path': 'buildprops.json', 16:19:01 INFO - 'config_files': ('talos/linux_config.py',), 16:19:01 INFO - 'default_actions': ('clobber', 16:19:01 INFO - 'read-buildbot-config', 16:19:01 INFO - 'download-and-extract', 16:19:01 INFO - 'populate-webroot', 16:19:01 INFO - 'create-virtualenv', 16:19:01 INFO - 'install', 16:19:01 INFO - 'run-tests'), 16:19:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:19:01 INFO - 'download_minidump_stackwalk': True, 16:19:01 INFO - 'download_symbols': 'ondemand', 16:19:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:19:01 INFO - 'tooltool.py': '/tools/tooltool.py', 16:19:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:19:01 INFO - '/tools/misc-python/virtualenv.py')}, 16:19:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:19:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:19:01 INFO - 'installer_path': 'installer.exe', 16:19:01 INFO - 'log_level': 'info', 16:19:01 INFO - 'log_name': 'talos', 16:19:01 INFO - 'log_to_console': True, 16:19:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:19:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:19:01 INFO - 'opt_config_files': (), 16:19:01 INFO - 'pip_index': False, 16:19:01 INFO - 'sps_profile': False, 16:19:01 INFO - 'sps_profile_interval': 0, 16:19:01 INFO - 'suite': 'dromaeojs', 16:19:01 INFO - 'system_bits': '32', 16:19:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:19:01 INFO - 'title': 'talos-linux64-ix-066', 16:19:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:19:01 INFO - 'use_talos_json': True, 16:19:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:19:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:19:01 INFO - 'work_dir': 'build'} 16:19:01 INFO - [mozharness: 2016-08-02 23:19:01.930235Z] Running clobber step. 16:19:01 INFO - Running pre-action listener: _resource_record_pre_action 16:19:01 INFO - Running main action method: clobber 16:19:01 INFO - rmtree: /builds/slave/test/build 16:19:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:19:02 INFO - Running post-action listener: _resource_record_post_action 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.386740Z] Finished clobber step (success) 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.386843Z] Running read-buildbot-config step. 16:19:02 INFO - Running pre-action listener: _resource_record_pre_action 16:19:02 INFO - Running main action method: read_buildbot_config 16:19:02 INFO - Using buildbot properties: 16:19:02 INFO - { 16:19:02 INFO - "project": "", 16:19:02 INFO - "product": "firefox", 16:19:02 INFO - "script_repo_revision": "production", 16:19:02 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:19:02 INFO - "repository": "", 16:19:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:19:02 INFO - "buildid": "20160802161836", 16:19:02 INFO - "stage_platform": "linux64-pgo", 16:19:02 INFO - "basedir": "/builds/slave/test", 16:19:02 INFO - "buildnumber": 1340, 16:19:02 INFO - "platform": "ubuntu64_hw", 16:19:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:19:02 INFO - "slavebuilddir": "test", 16:19:02 INFO - "branch": "mozilla-inbound", 16:19:02 INFO - "revision": "73a57814a495b29244ef5377e73488a3f3fabb15", 16:19:02 INFO - "slavename": "talos-linux64-ix-066", 16:19:02 INFO - "builduid": "67b63fe7460a4880835dcff42af618a4", 16:19:02 INFO - "repo_path": "integration/mozilla-inbound" 16:19:02 INFO - } 16:19:02 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:19:02 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 16:19:02 INFO - Running post-action listener: _resource_record_post_action 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.389214Z] Finished read-buildbot-config step (success) 16:19:02 INFO - [mozharness: 2016-08-02 23:19:02.389312Z] Running download-and-extract step. 16:19:02 INFO - Running pre-action listener: _resource_record_pre_action 16:19:02 INFO - Running main action method: download_and_extract 16:19:02 INFO - mkdir: /builds/slave/test/build/tests 16:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:19:02 INFO - Downloaded 1361 bytes. 16:19:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:19:02 INFO - Using the following test package requirements: 16:19:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:19:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:19:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'jsshell-linux-x86_64.zip'], 16:19:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:19:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:19:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:19:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:19:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:19:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:19:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:19:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:19:02 INFO - Downloaded 21811128 bytes. 16:19:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:19:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:19:03 INFO - Return code: 0 16:19:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:19:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:19:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:19:04 INFO - Downloaded 21811128 bytes. 16:19:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:19:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:19:06 INFO - Return code: 0 16:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:19:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:19:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:19:06 INFO - Downloaded 14413723 bytes. 16:19:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:19:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:19:06 INFO - Return code: 0 16:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:06 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:06 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:19:09 INFO - Downloaded 59398441 bytes. 16:19:09 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:09 INFO - mkdir: /builds/slave/test/properties 16:19:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:19:09 INFO - Writing to file /builds/slave/test/properties/build_url 16:19:09 INFO - Contents: 16:19:09 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:19:09 INFO - Running post-action listener: _resource_record_post_action 16:19:09 INFO - Running post-action listener: set_extra_try_arguments 16:19:09 INFO - [mozharness: 2016-08-02 23:19:09.919982Z] Finished download-and-extract step (success) 16:19:09 INFO - [mozharness: 2016-08-02 23:19:09.920216Z] Running populate-webroot step. 16:19:09 INFO - Running pre-action listener: _resource_record_pre_action 16:19:09 INFO - Running main action method: populate_webroot 16:19:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:19:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:19:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:19:09 INFO - '--tppagecycles', 16:19:09 INFO - '7'], 16:19:09 INFO - 'tests': ['tsvgm']}}, 16:19:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:19:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:19:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['damp', 'tps']}, 16:19:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'tests': ['damp', 'tps']}, 16:19:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['dromaeo_dom']}, 16:19:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['basic_compositor_video']}, 16:19:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:19:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['a11yr', 16:19:09 INFO - 'ts_paint', 16:19:09 INFO - 'tpaint', 16:19:09 INFO - 'sessionrestore', 16:19:09 INFO - 'sessionrestore_no_auto_restore', 16:19:09 INFO - 'tabpaint']}, 16:19:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['tsvgx', 16:19:09 INFO - 'tsvgr_opacity', 16:19:09 INFO - 'tart', 16:19:09 INFO - 'tscrollx', 16:19:09 INFO - 'cart']}, 16:19:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:09 INFO - 'tsvgr_opacity', 16:19:09 INFO - 'tart', 16:19:09 INFO - 'tscrollx', 16:19:09 INFO - 'cart']}, 16:19:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'talos_options': ['--disable-e10s'], 16:19:09 INFO - 'tests': ['tp5o']}, 16:19:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'tests': ['tp5o']}, 16:19:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'talos_options': ['--disable-e10s', 16:19:09 INFO - '--xperf_path', 16:19:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:09 INFO - 'tests': ['tp5n']}, 16:19:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:09 INFO - 'talos_options': ['--xperf_path', 16:19:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:09 INFO - 'tests': ['tp5n']}}, 16:19:09 INFO - 'talos.zip': {'path': '', 16:19:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:09 INFO - Running post-action listener: _resource_record_post_action 16:19:09 INFO - [mozharness: 2016-08-02 23:19:09.941804Z] Finished populate-webroot step (success) 16:19:09 INFO - [mozharness: 2016-08-02 23:19:09.941892Z] Running create-virtualenv step. 16:19:09 INFO - Running pre-action listener: _resource_record_pre_action 16:19:09 INFO - Running main action method: create_virtualenv 16:19:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:09 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 16:19:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:10 INFO - Using real prefix '/usr' 16:19:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:10 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:12 INFO - Installing pip.................done. 16:19:12 INFO - Return code: 0 16:19:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:12 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 16:19:12 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 16:19:12 INFO - Using env: {'DISPLAY': ':0', 16:19:12 INFO - 'HOME': '/home/cltbld', 16:19:12 INFO - 'LANG': 'en_US.UTF-8', 16:19:12 INFO - 'LANGUAGE': 'en_US:en', 16:19:12 INFO - 'LOGNAME': 'cltbld', 16:19:12 INFO - 'MAIL': '/var/mail/cltbld', 16:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:12 INFO - 'MOZ_NO_REMOTE': '1', 16:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:12 INFO - 'NO_EM_RESTART': '1', 16:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:12 INFO - 'PWD': '/builds/slave/test', 16:19:12 INFO - 'SHELL': '/bin/bash', 16:19:12 INFO - 'SHLVL': '1', 16:19:12 INFO - 'TERM': 'linux', 16:19:12 INFO - 'TMOUT': '86400', 16:19:12 INFO - 'USER': 'cltbld', 16:19:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:12 INFO - '_': '/tools/buildbot/bin/python'} 16:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:12 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 16:19:12 INFO - Cleaning up... 16:19:12 INFO - Return code: 0 16:19:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:19:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:12 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:12 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 16:19:12 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 16:19:12 INFO - Using env: {'DISPLAY': ':0', 16:19:12 INFO - 'HOME': '/home/cltbld', 16:19:12 INFO - 'LANG': 'en_US.UTF-8', 16:19:12 INFO - 'LANGUAGE': 'en_US:en', 16:19:12 INFO - 'LOGNAME': 'cltbld', 16:19:12 INFO - 'MAIL': '/var/mail/cltbld', 16:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:12 INFO - 'MOZ_NO_REMOTE': '1', 16:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:12 INFO - 'NO_EM_RESTART': '1', 16:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:12 INFO - 'PWD': '/builds/slave/test', 16:19:12 INFO - 'SHELL': '/bin/bash', 16:19:12 INFO - 'SHLVL': '1', 16:19:12 INFO - 'TERM': 'linux', 16:19:12 INFO - 'TMOUT': '86400', 16:19:12 INFO - 'USER': 'cltbld', 16:19:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:12 INFO - '_': '/tools/buildbot/bin/python'} 16:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:13 INFO - Downloading/unpacking psutil>=3.1.1 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:13 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 16:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:15 INFO - Installing collected packages: psutil 16:19:15 INFO - Running setup.py install for psutil 16:19:15 INFO - building 'psutil._psutil_linux' extension 16:19:15 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 16:19:15 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 16:19:15 INFO - building 'psutil._psutil_posix' extension 16:19:15 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 16:19:15 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 16:19:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:15 INFO - Successfully installed psutil 16:19:15 INFO - Cleaning up... 16:19:15 INFO - Return code: 0 16:19:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:19:15 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:15 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:15 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:15 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 16:19:15 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 16:19:15 INFO - Using env: {'DISPLAY': ':0', 16:19:15 INFO - 'HOME': '/home/cltbld', 16:19:15 INFO - 'LANG': 'en_US.UTF-8', 16:19:15 INFO - 'LANGUAGE': 'en_US:en', 16:19:15 INFO - 'LOGNAME': 'cltbld', 16:19:15 INFO - 'MAIL': '/var/mail/cltbld', 16:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:15 INFO - 'MOZ_NO_REMOTE': '1', 16:19:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:15 INFO - 'NO_EM_RESTART': '1', 16:19:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:15 INFO - 'PWD': '/builds/slave/test', 16:19:15 INFO - 'SHELL': '/bin/bash', 16:19:15 INFO - 'SHLVL': '1', 16:19:15 INFO - 'TERM': 'linux', 16:19:15 INFO - 'TMOUT': '86400', 16:19:15 INFO - 'USER': 'cltbld', 16:19:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:15 INFO - '_': '/tools/buildbot/bin/python'} 16:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:19:15 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 16:19:15 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 16:19:15 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 16:19:15 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 16:19:15 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 16:19:15 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 16:19:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:19:17 INFO - Installing collected packages: mozsystemmonitor 16:19:17 INFO - Running setup.py install for mozsystemmonitor 16:19:18 INFO - Successfully installed mozsystemmonitor 16:19:18 INFO - Cleaning up... 16:19:18 INFO - Return code: 0 16:19:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:19:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:18 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:18 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 16:19:18 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 16:19:18 INFO - Using env: {'DISPLAY': ':0', 16:19:18 INFO - 'HOME': '/home/cltbld', 16:19:18 INFO - 'LANG': 'en_US.UTF-8', 16:19:18 INFO - 'LANGUAGE': 'en_US:en', 16:19:18 INFO - 'LOGNAME': 'cltbld', 16:19:18 INFO - 'MAIL': '/var/mail/cltbld', 16:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:18 INFO - 'MOZ_NO_REMOTE': '1', 16:19:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:18 INFO - 'NO_EM_RESTART': '1', 16:19:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:18 INFO - 'PWD': '/builds/slave/test', 16:19:18 INFO - 'SHELL': '/bin/bash', 16:19:18 INFO - 'SHLVL': '1', 16:19:18 INFO - 'TERM': 'linux', 16:19:18 INFO - 'TMOUT': '86400', 16:19:18 INFO - 'USER': 'cltbld', 16:19:18 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:18 INFO - '_': '/tools/buildbot/bin/python'} 16:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:18 INFO - Downloading/unpacking jsonschema==2.5.1 16:19:18 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 16:19:18 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 16:19:18 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 16:19:18 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 16:19:18 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 16:19:18 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 16:19:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 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 16:19:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:20 INFO - warning: no files found matching '*.txt' 16:19:20 INFO - no previously-included directories found matching 'build' 16:19:20 INFO - no previously-included directories found matching 'dist' 16:19:20 INFO - no previously-included directories found matching '.git*' 16:19:20 INFO - Installing collected packages: jsonschema, functools32 16:19:20 INFO - Running setup.py install for functools32 16:19:20 INFO - warning: no files found matching '*.txt' 16:19:20 INFO - no previously-included directories found matching 'build' 16:19:20 INFO - no previously-included directories found matching 'dist' 16:19:20 INFO - no previously-included directories found matching '.git*' 16:19:20 INFO - Successfully installed jsonschema functools32 16:19:20 INFO - Cleaning up... 16:19:20 INFO - Return code: 0 16:19:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:20 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:20 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 16:19:20 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 16:19:20 INFO - Using env: {'DISPLAY': ':0', 16:19:20 INFO - 'HOME': '/home/cltbld', 16:19:20 INFO - 'LANG': 'en_US.UTF-8', 16:19:20 INFO - 'LANGUAGE': 'en_US:en', 16:19:20 INFO - 'LOGNAME': 'cltbld', 16:19:20 INFO - 'MAIL': '/var/mail/cltbld', 16:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:20 INFO - 'MOZ_NO_REMOTE': '1', 16:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:20 INFO - 'NO_EM_RESTART': '1', 16:19:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:20 INFO - 'PWD': '/builds/slave/test', 16:19:20 INFO - 'SHELL': '/bin/bash', 16:19:20 INFO - 'SHLVL': '1', 16:19:20 INFO - 'TERM': 'linux', 16:19:20 INFO - 'TMOUT': '86400', 16:19:20 INFO - 'USER': 'cltbld', 16:19:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:20 INFO - '_': '/tools/buildbot/bin/python'} 16:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:19:21 INFO - Cleaning up... 16:19:21 INFO - Return code: 0 16:19:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:21 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:21 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 16:19:21 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 16:19:21 INFO - Using env: {'DISPLAY': ':0', 16:19:21 INFO - 'HOME': '/home/cltbld', 16:19:21 INFO - 'LANG': 'en_US.UTF-8', 16:19:21 INFO - 'LANGUAGE': 'en_US:en', 16:19:21 INFO - 'LOGNAME': 'cltbld', 16:19:21 INFO - 'MAIL': '/var/mail/cltbld', 16:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:21 INFO - 'MOZ_NO_REMOTE': '1', 16:19:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:21 INFO - 'NO_EM_RESTART': '1', 16:19:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:21 INFO - 'PWD': '/builds/slave/test', 16:19:21 INFO - 'SHELL': '/bin/bash', 16:19:21 INFO - 'SHLVL': '1', 16:19:21 INFO - 'TERM': 'linux', 16:19:21 INFO - 'TMOUT': '86400', 16:19:21 INFO - 'USER': 'cltbld', 16:19:21 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:21 INFO - '_': '/tools/buildbot/bin/python'} 16:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:21 INFO - Downloading/unpacking blobuploader==1.2.4 16:19:21 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 16:19:21 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 16:19:21 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 16:19:21 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 16:19:21 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 16:19:21 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 16:19:23 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19: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 16:19:23 INFO - Downloading docopt-0.6.1.tar.gz 16:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:23 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:23 INFO - Running setup.py install for blobuploader 16:19:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:24 INFO - Running setup.py install for requests 16:19:24 INFO - Running setup.py install for docopt 16:19:24 INFO - Successfully installed blobuploader requests docopt 16:19:24 INFO - Cleaning up... 16:19:24 INFO - Return code: 0 16:19:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:24 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:24 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 16:19:24 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 16:19:24 INFO - Using env: {'DISPLAY': ':0', 16:19:24 INFO - 'HOME': '/home/cltbld', 16:19:24 INFO - 'LANG': 'en_US.UTF-8', 16:19:24 INFO - 'LANGUAGE': 'en_US:en', 16:19:24 INFO - 'LOGNAME': 'cltbld', 16:19:24 INFO - 'MAIL': '/var/mail/cltbld', 16:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:24 INFO - 'MOZ_NO_REMOTE': '1', 16:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:24 INFO - 'NO_EM_RESTART': '1', 16:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:24 INFO - 'PWD': '/builds/slave/test', 16:19:24 INFO - 'SHELL': '/bin/bash', 16:19:24 INFO - 'SHLVL': '1', 16:19:24 INFO - 'TERM': 'linux', 16:19:24 INFO - 'TMOUT': '86400', 16:19:24 INFO - 'USER': 'cltbld', 16:19:24 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:24 INFO - '_': '/tools/buildbot/bin/python'} 16:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:24 INFO - Running setup.py (path:/tmp/pip-aEikKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:24 INFO - Running setup.py (path:/tmp/pip-Qy2lNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:24 INFO - Running setup.py (path:/tmp/pip-BvNFOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:25 INFO - Running setup.py (path:/tmp/pip-4auFCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:25 INFO - Running setup.py (path:/tmp/pip-FS1K4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:25 INFO - Running setup.py (path:/tmp/pip-TAo12u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:25 INFO - Running setup.py (path:/tmp/pip-fHdRhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:25 INFO - Running setup.py (path:/tmp/pip-y1TE_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:25 INFO - Running setup.py (path:/tmp/pip-wBczsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:25 INFO - Running setup.py (path:/tmp/pip-xZNxS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:25 INFO - Running setup.py (path:/tmp/pip-WMU2s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:25 INFO - Running setup.py (path:/tmp/pip-f9t8iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:25 INFO - Running setup.py (path:/tmp/pip-NYNjTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:25 INFO - Running setup.py (path:/tmp/pip-GRs8KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:25 INFO - Running setup.py (path:/tmp/pip-TVN0gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:25 INFO - Running setup.py (path:/tmp/pip-95SqFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:25 INFO - Running setup.py (path:/tmp/pip-uslIgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:26 INFO - Running setup.py install for manifestparser 16:19:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozcrash 16:19:26 INFO - Running setup.py install for mozdebug 16:19:26 INFO - Running setup.py install for mozdevice 16:19:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozfile 16:19:26 INFO - Running setup.py install for mozhttpd 16:19:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozinfo 16:19:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozInstall 16:19:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozleak 16:19:26 INFO - Running setup.py install for mozlog 16:19:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Running setup.py install for moznetwork 16:19:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Running setup.py install for mozprocess 16:19:27 INFO - Running setup.py install for mozprofile 16:19:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Running setup.py install for mozrunner 16:19:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Running setup.py install for mozscreenshot 16:19:27 INFO - Running setup.py install for moztest 16:19:27 INFO - Running setup.py install for mozversion 16:19:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:27 INFO - Cleaning up... 16:19:27 INFO - Return code: 0 16:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:27 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:27 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 16:19:27 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 16:19:27 INFO - Using env: {'DISPLAY': ':0', 16:19:27 INFO - 'HOME': '/home/cltbld', 16:19:27 INFO - 'LANG': 'en_US.UTF-8', 16:19:27 INFO - 'LANGUAGE': 'en_US:en', 16:19:27 INFO - 'LOGNAME': 'cltbld', 16:19:27 INFO - 'MAIL': '/var/mail/cltbld', 16:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:27 INFO - 'MOZ_NO_REMOTE': '1', 16:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:27 INFO - 'NO_EM_RESTART': '1', 16:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:27 INFO - 'PWD': '/builds/slave/test', 16:19:27 INFO - 'SHELL': '/bin/bash', 16:19:27 INFO - 'SHLVL': '1', 16:19:27 INFO - 'TERM': 'linux', 16:19:27 INFO - 'TMOUT': '86400', 16:19:27 INFO - 'USER': 'cltbld', 16:19:27 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:27 INFO - '_': '/tools/buildbot/bin/python'} 16:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:28 INFO - Running setup.py (path:/tmp/pip-tzmdCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:28 INFO - Running setup.py (path:/tmp/pip-SG_hFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:28 INFO - Running setup.py (path:/tmp/pip-bYP5i5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:28 INFO - Running setup.py (path:/tmp/pip-YNve7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:28 INFO - Running setup.py (path:/tmp/pip-Cb4aHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:28 INFO - Running setup.py (path:/tmp/pip-eec8pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:28 INFO - Running setup.py (path:/tmp/pip-w7hjxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:28 INFO - Running setup.py (path:/tmp/pip-UP3U0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:28 INFO - Running setup.py (path:/tmp/pip-Ia98S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:28 INFO - Running setup.py (path:/tmp/pip-mBlzF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:28 INFO - Running setup.py (path:/tmp/pip-Rjvngu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:28 INFO - Running setup.py (path:/tmp/pip-viv9I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:28 INFO - Running setup.py (path:/tmp/pip-VzrTE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:28 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)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:28 INFO - Running setup.py (path:/tmp/pip-QYkJoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:29 INFO - Running setup.py (path:/tmp/pip-Mtb_gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:29 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)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:29 INFO - Running setup.py (path:/tmp/pip-z0rvUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:29 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)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:29 INFO - Running setup.py (path:/tmp/pip-99GD6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:29 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)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:29 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)) 16:19:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:29 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 16:19:31 INFO - Downloading blessings-1.6.tar.gz 16:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:31 INFO - Installing collected packages: blessings 16:19:31 INFO - Running setup.py install for blessings 16:19:31 INFO - Successfully installed blessings 16:19:31 INFO - Cleaning up... 16:19:31 INFO - Return code: 0 16:19:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:31 INFO - Reading from file tmpfile_stdout 16:19:31 INFO - Current package versions: 16:19:31 INFO - argparse == 1.2.1 16:19:31 INFO - blessings == 1.6 16:19:31 INFO - blobuploader == 1.2.4 16:19:31 INFO - docopt == 0.6.1 16:19:31 INFO - functools32 == 3.2.3-2 16:19:31 INFO - jsonschema == 2.5.1 16:19:31 INFO - manifestparser == 1.1 16:19:31 INFO - mozInstall == 1.12 16:19:31 INFO - mozcrash == 0.17 16:19:31 INFO - mozdebug == 0.1 16:19:31 INFO - mozdevice == 0.48 16:19:31 INFO - mozfile == 1.2 16:19:31 INFO - mozhttpd == 0.7 16:19:31 INFO - mozinfo == 0.9 16:19:31 INFO - mozleak == 0.1 16:19:31 INFO - mozlog == 3.3 16:19:31 INFO - moznetwork == 0.27 16:19:31 INFO - mozprocess == 0.23 16:19:31 INFO - mozprofile == 0.28 16:19:31 INFO - mozrunner == 6.12 16:19:31 INFO - mozscreenshot == 0.1 16:19:31 INFO - mozsystemmonitor == 0.3 16:19:31 INFO - moztest == 0.7 16:19:31 INFO - mozversion == 1.4 16:19:31 INFO - psutil == 3.1.1 16:19:31 INFO - requests == 1.2.3 16:19:31 INFO - wsgiref == 0.1.2 16:19:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:31 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 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19:31 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 16:19:31 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 16:19:31 INFO - Using env: {'DISPLAY': ':0', 16:19:31 INFO - 'HOME': '/home/cltbld', 16:19:31 INFO - 'LANG': 'en_US.UTF-8', 16:19:31 INFO - 'LANGUAGE': 'en_US:en', 16:19:31 INFO - 'LOGNAME': 'cltbld', 16:19:31 INFO - 'MAIL': '/var/mail/cltbld', 16:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:31 INFO - 'MOZ_NO_REMOTE': '1', 16:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:31 INFO - 'NO_EM_RESTART': '1', 16:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:31 INFO - 'PWD': '/builds/slave/test', 16:19:31 INFO - 'SHELL': '/bin/bash', 16:19:31 INFO - 'SHLVL': '1', 16:19:31 INFO - 'TERM': 'linux', 16:19:31 INFO - 'TMOUT': '86400', 16:19:31 INFO - 'USER': 'cltbld', 16:19:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:31 INFO - '_': '/tools/buildbot/bin/python'} 16:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 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)) 16:19:31 INFO - Cleaning up... 16:19:31 INFO - Return code: 0 16:19:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd38030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe9b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xff9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, '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': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', '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 16:19: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', 'jsonschema'] in /builds/slave/test/build 16:19: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 jsonschema 16:19:31 INFO - Using env: {'DISPLAY': ':0', 16:19:31 INFO - 'HOME': '/home/cltbld', 16:19:31 INFO - 'LANG': 'en_US.UTF-8', 16:19:31 INFO - 'LANGUAGE': 'en_US:en', 16:19:31 INFO - 'LOGNAME': 'cltbld', 16:19:31 INFO - 'MAIL': '/var/mail/cltbld', 16:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:31 INFO - 'MOZ_NO_REMOTE': '1', 16:19:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:31 INFO - 'NO_EM_RESTART': '1', 16:19:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:31 INFO - 'PWD': '/builds/slave/test', 16:19:31 INFO - 'SHELL': '/bin/bash', 16:19:31 INFO - 'SHLVL': '1', 16:19:31 INFO - 'TERM': 'linux', 16:19:31 INFO - 'TMOUT': '86400', 16:19:31 INFO - 'USER': 'cltbld', 16:19:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:31 INFO - '_': '/tools/buildbot/bin/python'} 16:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:19:31 INFO - Cleaning up... 16:19:31 INFO - Return code: 0 16:19:31 INFO - Running post-action listener: _resource_record_post_action 16:19:31 INFO - Running post-action listener: _start_resource_monitoring 16:19:32 INFO - Starting resource monitoring. 16:19:32 INFO - [mozharness: 2016-08-02 23:19:32.017402Z] Finished create-virtualenv step (success) 16:19:32 INFO - [mozharness: 2016-08-02 23:19:32.017878Z] Running install step. 16:19:32 INFO - Running pre-action listener: _resource_record_pre_action 16:19:32 INFO - Running main action method: install 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Output received: 16:19:32 INFO - Usage: mozinstall [options] installer 16:19:32 INFO - Options: 16:19:32 INFO - -h, --help show this help message and exit 16:19:32 INFO - -d DEST, --destination=DEST 16:19:32 INFO - Directory to install application into. [default: 16:19:32 INFO - "/builds/slave/test"] 16:19:32 INFO - --app=APP Application being installed. [default: firefox] 16:19:32 INFO - mkdir: /builds/slave/test/build/application 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:19:49 INFO - Reading from file tmpfile_stdout 16:19:49 INFO - Output received: 16:19:49 INFO - /builds/slave/test/build/application/firefox/firefox 16:19:49 INFO - Running post-action listener: _resource_record_post_action 16:19:49 INFO - [mozharness: 2016-08-02 23:19:49.164286Z] Finished install step (success) 16:19:49 INFO - [mozharness: 2016-08-02 23:19:49.164566Z] Running run-tests step. 16:19:49 INFO - Running pre-action listener: _resource_record_pre_action 16:19:49 INFO - Running main action method: run_tests 16:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:19:49 INFO - Python 2.7.3 16:19:49 INFO - Return code: 0 16:19:49 INFO - grabbing minidump binary from tooltool 16:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:49 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 0xff73b0>, '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 0xff82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:19:49 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 16:19:49 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 16:19:49 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 16:19:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:19:49 INFO - Return code: 0 16:19:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:19:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:19:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:19:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:19:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:19:49 INFO - Using env: {'DISPLAY': ':0', 16:19:49 INFO - 'HOME': '/home/cltbld', 16:19:49 INFO - 'LANG': 'en_US.UTF-8', 16:19:49 INFO - 'LANGUAGE': 'en_US:en', 16:19:49 INFO - 'LOGNAME': 'cltbld', 16:19:49 INFO - 'MAIL': '/var/mail/cltbld', 16:19:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:19:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:49 INFO - 'MOZ_NO_REMOTE': '1', 16:19:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:19:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:49 INFO - 'NO_EM_RESTART': '1', 16:19:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:49 INFO - 'PWD': '/builds/slave/test', 16:19:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:19:49 INFO - 'SHELL': '/bin/bash', 16:19:49 INFO - 'SHLVL': '1', 16:19:49 INFO - 'TERM': 'linux', 16:19:49 INFO - 'TMOUT': '86400', 16:19:49 INFO - 'USER': 'cltbld', 16:19:49 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027', 16:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:49 INFO - '_': '/tools/buildbot/bin/python'} 16:19:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:19:49 INFO - mozversion application_buildid: 20160802133030 16:19:49 INFO - mozversion application_changeset: 73a57814a495b29244ef5377e73488a3f3fabb15 16:19:49 INFO - mozversion application_display_name: Nightly 16:19:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:19:49 INFO - mozversion application_name: Firefox 16:19:49 INFO - mozversion application_remotingname: firefox 16:19:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:49 INFO - mozversion application_vendor: Mozilla 16:19:49 INFO - mozversion application_version: 51.0a1 16:19:49 INFO - mozversion platform_buildid: 20160802133030 16:19:49 INFO - mozversion platform_changeset: 73a57814a495b29244ef5377e73488a3f3fabb15 16:19:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:19:49 INFO - mozversion platform_version: 51.0a1 16:19:49 INFO - using testdate: 1470179989 16:19:49 INFO - actual date: 1470179989 16:19:49 INFO - starting webserver on 'localhost:44879' 16:19:49 INFO - SUITE-START | Running 2 tests 16:19:49 INFO - TEST-START | dromaeo_css 16:19:49 INFO - operating with platform_type : linux_ 16:19:49 INFO - Initialising browser for dromaeo_css test... 16:19:49 INFO - TEST-INFO | started process 9143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppeU4qM/profile http://localhost:44879/getInfo.html) 16:19:53 INFO - PROCESS | 9143 | __metrics Screen width/height:1600/1200 16:19:53 INFO - PROCESS | 9143 | colorDepth:24 16:19:53 INFO - PROCESS | 9143 | Browser inner width/height: 1024/697 16:19:53 INFO - PROCESS | 9143 | __metrics 16:19:54 INFO - PROCESS | 9143 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:19:54 INFO - PROCESS | 9143 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:19:55 INFO - TEST-INFO | 9143: exit 0 16:19:55 INFO - Browser initialized. 16:19:55 INFO - Running cycle 1/1 for dromaeo_css test... 16:19:55 INFO - TEST-INFO | started process 9226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppeU4qM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:07 INFO - PROCESS | 9226 | RSS: Main: 156172288 16:20:07 INFO - PROCESS | 9226 | 16:20:59 INFO - PROCESS | 9226 | 1470180059167 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:23:08 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44879/tests/dromaeo/cssquery-ext.html) 16:23:09 INFO - PROCESS | 9226 | RSS: Main: 247922688 16:23:09 INFO - PROCESS | 9226 | 16:26:12 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-ext.html (next: http://localhost:44879/tests/dromaeo/cssquery-jquery.html) 16:26:12 INFO - PROCESS | 9226 | RSS: Main: 254132224 16:26:12 INFO - PROCESS | 9226 | 16:29:14 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44879/tests/dromaeo/cssquery-mootools.html) 16:29:15 INFO - PROCESS | 9226 | RSS: Main: 189227008 16:29:15 INFO - PROCESS | 9226 | 16:32:17 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44879/tests/dromaeo/cssquery-prototype.html) 16:32:17 INFO - PROCESS | 9226 | RSS: Main: 189190144 16:32:17 INFO - PROCESS | 9226 | 16:35:20 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44879/tests/dromaeo/cssquery-yui.html) 16:35:21 INFO - PROCESS | 9226 | RSS: Main: 192266240 16:35:21 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | Cycle 1(1): loaded http://localhost:44879/tests/dromaeo/cssquery-yui.html (next: http://localhost:44879/tests/dromaeo/cssquery-dojo.html) 16:38:24 INFO - PROCESS | 9226 | RSS: Main: 265236480 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | __start_tp_report 16:38:24 INFO - PROCESS | 9226 | _x_x_mozilla_page_load 16:38:24 INFO - PROCESS | 9226 | _x_x_mozilla_page_load_details 16:38:24 INFO - PROCESS | 9226 | |i|pagename|runs| 16:38:24 INFO - PROCESS | 9226 | |0;dojo.html;3030.9696903030977;3047.603811504505;3056.8930087446915;3060.433819743347;3090.6291245050597;5228.137357336044;5298.655587386822;5304.814331498398;5400.675959442431;5405;5343.198520221959;5370.060239458089;5404.891902161964;5424.050791111559;5437.265969094171;4811.037792441508;4883.1698611236;4902.97548512257;4905.681130726509;5089.058524173021;4988.301637770697;4998.625103117262;5020.071286811952;5031.2453132030305;5041.319421878036;4721.173794585934;4840.080384726926;4880.951190488086;4889.046635906;4939.58013568847;4836.927446088312;4840.467548569654;4851.805927762885;4853.39332583427;4955.950440495585;4833.178359678863;4863.221884498464;4879.585235255007;4883.9511604883855;4924.507549245082;5200.479952004807;5201.609879259033;5276.551493123089;5284.682919024871;5368.570514358842;5619.044762390404;5652.5195358394585;5653.773849046033;5654.151877218409;5679.488846003879;5676.432356764331;5700.800471983461;5717.085266357363;5732.254806875079;5736.282964629421;5214.5046220609365;5308.575313974873;5362.4637536246455;5368.758405871745;5420.457954204627;5934;5989.371116032841;5989.760409583655;5992.280926288786;5994;5686.800961966312;5687.203791469197;5729.312482502127;5733.971330143323;5738.598298119092;5528.226048353234;5663.773449062074;5710.97202503545;5725.914111288334;5815.389384114692;5739.139129130664;5747;5748.281464816898;5749.281339832521;5755.280589926259;5697.316322041299;5731.369549349568;5756.453136951983;5770.336411312753;5785.305763308345;4287.8499252526635;4326.6538676905775;4330.047389574289;4370.847020354336;4376.212281789245;3846.1538461538416;3891.2217556488818;3920.6079392061138;3941.6846652268323;3942.48747662802;5338.065838478251;5339.305890234323;5362.597805164551;5386.59600529954;5391.541718953933;5658.122990936329;5685.516731077904;5688.033034384164;5690.601657883991;5691.544676425876;5232.372115346183;5334.5732341412595;5343.86640333987;5364.114921038063;5427.267318911975;4878.121938051185;4879.365682461328;4879.658423910292;4882.877928051827;4894.926576101361;5878;5878.794242201502;5882.911756323659;5883.058710606282;5933.406659334032;5541.695206763667;5657.6888271143835;5659.915101273402;5662.603617746883;5699.800506982236;5870.236869206976;5934.584579079423;5968.731407086605;5983.55123365758;5988;5618.4662457066515;5622.493975542221;5629.605927585029;5631.774729010794;5656.547476201977;5525;5691.260136182435;5695.971520142539;5765.654060756368;5769.596128271149;5603.467670571289;5660.88678226425;5667.036603777286;5670.319561652628;5705.486506214461;5580.832575022756;5616.157576363578;5616.66300022;5632.718364081861;5653.802116925888;5406.864828379322;5481.75332110048;5582.888342233214;5587.664740115606;5594.552435805044;5596.328440586993;5653.067243904709;5667.801626943037;5713.571482138773;5724.74238659253;5539.916901246565;5542.972285138549;5549.722513874371;5550.361708403424;5554.083576210041 16:38:24 INFO - PROCESS | 9226 | |1;ext.html;602.605293532736;621.4748537485824;626.3329554024966;630.5523078614184;636.891728406171;44110;45447.545524544745;45495.999999999956;49033;49152.50847491524;46765.53234467654;47052.52947470524;47104;47207.52792472074;47262.52737472624;8917.37578369516;9126.58930348136;9130.086991300883;9137.68018119366;9138;8899.866502002475;8918.375713700043;8963.955180224122;8973.46159230445;8983.19151276385;8993.730188094334;9060.954695226515;9061.048589898031;9065.274778017776;9072.455652660861;8981.506017169053;8983.910160898438;8987.685431009948;9016.729498115066;9022.278217742565;8916.955415222947;8988.191062804313;8997.100289971017;8997.460152390877;9005.054469280696;44764.77617611924;44999.77500112511;45141;45145.774271128765;45166.54833451656;3460.411730005905;3583.874564390234;3706.684931780802;3978.224246271949;4220.620144187038;33626.83186584076;34131.14672133192;34339.828300858586;34408;35455.29089418223;4219.346001369762;4348.978255108705;4376.387305777162;4464.330350447426;4501.774911254424;377298;378442;381179;381401;381572;59857.70071149617;59897.40102599005;59942;60251.698741506014;60280.698596506736;63996;64172;64296.67851660712;64410;64568.677156613914;13830.792538111937;13961;13961.581152565439;13962.371693273826;13981.370838312301;11004.174686898514;11005.119590432907;11008.064314533194;11013.118950484102;11086.944565277123;15818.209089545708;17270.568235794206;17435.912820435817;17553.122343882755;17657.38199163023;5481.698506582096;6171.759826091248;6553.934460655332;6623.9006414903815;6639.170103737033;991.5389343954952;1032.5198782565992;1092.0990183098975;1108.6840250528721;1171.2503997441645;32975.50536741951;33479.497807532905;33914.49128263078;34228.65771342305;34410.48384274238;1071.8124328242527;1074.9301295415773;1075.5590208014676;1114.2311804854623;1133.6769020829024;16916.91541542309;17548.27834105942;17601.7359739604;17691.823081769275;17784.91107544454;560180;563262;564574;565363;565717.0569765827;60718;60884.69557652184;60912.39087609156;60969.73211875215;60977.69511152416;1245.315076707807;1330.401319406271;1339.370495866941;1369.1716511510392;1382.1845111384332;33117.503237450976;34126.65873341235;34750.13124671904;34895.12762180966;34991.65008349986;52862.20706689326;53275.20087198618;53313.46686533085;53341;53383;439382;442027;442129;442452;442607;28433.43133137284;29150.12549623515;29349.559756604096;30402.239944001576;30682.386352273275;50198.247026295365;50275.24587131269;50403.243951340024;50985;52215.73892130515;41917.79041104897;42578;42724.572754272056;42741.572584275;44720.77639611781;42468.362974556025;42596;42888.57111428846;43865.34201986909;43935.56064439315;42704;42908.78545607252;43982.12035759331;44202.3369649462;44409;35789.46315805317;37861.81069094747;38538;38765.6123438762;38897.41653875138;28773.42453150967;28844;29026.564601531416;29152.70847291585;29315.80131490104 16:38:24 INFO - PROCESS | 9226 | |2;jquery.html;372.2629194195491;386.20249185432095;403.25599269348055;403.6105158522026;415.8024938154377;14452.783208251885;17945.371911983082;19105.14026868791;20083.497912552193;20103.798962010376;18970.430887073377;19001.429957101274;19048.904755476236;19055.33306334275;19056.523586910334;5651.124075768253;5690.004249256384;5787.826365209051;5813.73838177281;5849.298084229898;5792.884142317151;5812.360640329561;5850.853728656775;5852.736626851802;5856.150858125569;5817.389174136718;5823.359430462646;5839.386864379243;6209.348018458042;6512.674366281691;6584.539082264243;6622.403983641495;6631.602103873759;6636;6641.966790166042;5693.886122277573;5822.330432000332;5860.062390017577;5865.472107510344;5867.413258674141;13620.114692544952;13738.450461981509;13745;13855.930720346434;13885;2145.6137895178863;2157.6439887418555;2299.5745787029455;2316.652502124678;2451.6935383077116;9891;9940.15508681763;10029.749256268578;10464.633737819217;10516.737081572945;1844.9169787359601;1939.5539026023953;1955.3840540229783;1961.8822870627807;1993.4418362858423;79850.60074699647;84947.99999999939;86608;88121;88207.55896220542;10879.12966962641;10934.289271197347;10936.125109991182;10943.453892073958;11121.721956951142;15381.615459613598;15419.229038548028;15439.768403473958;15457.381704731684;15482.5355239343;7581.848363032708;7674.194209608022;7737.92262077372;7809.3752499799875;7825.960870195612;7122;7122.394596459359;7157;7325.084364454443;7340.706371745177;4598.540145985442;4662.510436404196;4700.130475861976;4702.95297047032;4736.573708366263;1894.9810501894804;1900.5058684741791;1951.945949187423;1965.49879780655;2003.5692326149656;951.414879848888;954.1775209350246;956.6460409648336;991.6727479931606;1003.9949800250952;18822.24711011544;19744.802551974582;19760.90119549393;19857.702134467996;19998.600027999648;973.2894986659636;994.5773046455228;994.6071301835734;996.5565323431149;1063.5639387850945;10172.898271017195;10726.678199654023;10852.186086043579;10885.129189664807;10939;485666;486399;490977;497393;500882;92673;93131;93725;93980.53009734908;94194.52902735442;964.6816550538306;966.6133546581283;978.7308490165119;983.8229118758549;995.0944640377367;16758;17220.311187552357;17361.218745156246;18454.35409760652;18502.167402466654;43779.562204378824;44020.15778264452;44417.333739994574;44977.77511112423;45455;54816.72591637016;58571.70714146402;58878;59083.11375329288;59113.40886591251;8401.747947561584;8430.114837941928;8479.491230526188;8749.65001399937;8867.955660221658;12973.870261297267;13033.229791951466;13137.211767293993;13595.864041359855;13734.793978090136;10476.790464190826;11330.490127944098;11418.629764428322;11557.653270401564;11646.650600481998;10274.486275686037;10828.729281768019;11330.490127944428;11472.655820325404;11646.475908584303;11868.287902725864;11872.940635296769;11893.643190704293;12113;12265.352926186839;8537.957310213409;8553;8689.30485561141;8768.99156596974;8935.553222338987;10998.890011099787;11496.367699776592;11816.76366472683;12342;12345.567905123278 16:38:24 INFO - PROCESS | 9226 | |3;mootools.html;1314.8093526438672;1451.151076620177;1513.727529044772;1622.675464907018;1777.0581591756375;8602.526861022641;8990.37067405282;9024.232940200074;9179.862302065474;9212.078792120785;9024.774380640487;9137.131972462623;9138.543072846347;9142.40574362666;9145.725628231146;2305.9077636894503;2331.3006098170567;2331.895064722092;2335.7781010803947;2335.92992210234;2308.803751681109;2399.4121440247163;2402.459446624513;2406.4465173010217;2407.0492155598618;2381.880905954704;2382.4282172278704;2390.605550084234;2426.2478631624163;2426.296374051523;2314.5486630107134;2355.2934119764086;2360.4334959609655;2361.0791791201445;2384.3562238195764;2285.520040791436;2312.8034117100065;2314.0859360552486;2318.420394901275;2321.7097862767155;8956.462612243286;9138.634454621808;9188.770280742969;9192.632294708204;9193.862092068624;5412.864678383072;5573.665580065209;5611.158326251054;5611.635243709186;5618.0449323614675;3425.366307233145;3441.15691655546;3476.28736109097;3504.0363899927725;3508.386032444312;1201.2492192379764;1208.1482554798845;1213.8422005139364;1221.8961388282007;1263.3178083834719;12930.870691293061;12976.35118244084;13025.478980840755;13028.088033837636;13041.608751737558;4525.52481989393;4634.21218392874;4779.259214821708;4786.377770889762;4820.084184005098;10015.048570385801;10026.248031397528;10031.548580313904;10035.197184225244;10036.64871729486;5276.314079169683;5312.256284120226;5323;5324.707141107198;5327.38735045467;4304.311310190355;4824.421069471686;4915.36100306965;4949.084419382426;4983.401991760939;3802.6957843372466;3893.9415908761393;3961.6632586230494;3994.6404823565435;4002.0995276063236;1121.2039451989017;1124.2973141786383;1133.7392399748105;1152.7175841918615;1174.2015429507846;901.4771432569095;956.9569369378395;968.3995922527937;970.752458123174;987.7301525186037;6026.397360263939;6069.2110025696375;6246;6377.840553986095;6386.457151142205;454.54771014147497;455.3374839608362;455.67191622032084;459.10932790386545;460.43596594171873;4278.122984788111;4288.592583704543;4297.140571885635;4349.412829268008;4357.346398040319;11208.607698730506;11511.712207194887;11584.76830463386;11722.296662200295;11735.530578776925;10904.78190436187;11474;11558.190926635054;11602.94198529002;11861.703457413461;8463.407561470638;8656.134386561294;8662.393632445668;8665.913340866511;8667.609957551926;5975.37258587842;6113.755449781959;6119.755209791559;6202.937970620236;6256.436920677161;10553.102986246255;10589.046985771318;10639.840402394126;10640.733981650521;10795.892041079489;10453.529591168568;10468.738281543023;10824.945875270574;10825.621103261348;10951.34291942486;7377.299156580117;7611.858221266854;7617.200193979553;8098.595070246582;8330.833383332178;8316.83366332682;8636.050034496326;8869.73390798277;8903.643854245758;9002.144796244347;7664.655090520829;7738.729144479917;7986.241307075818;8264.669413223404;8317.750467485985;7685.884711729217;7764.650590723544;7804.9609751950875;7836;7858.803529911798;7380.261973802577;7484.43866709988;7500.212477689764;7500.737474188377;7504.962475187589;5366.892662146814;5501.642393244426;5505.752241149238;5585.748641311232;5644.915326270191;5500.862478437911;5630.887382252414;5688.459596338506;5749.9712501437225;5765.250517432707 16:38:24 INFO - PROCESS | 9226 | |4;prototype.html;324.58939441606367;326.2854348975743;331.9917002074948;336.13110110364704;337.27822459935743;5275.366955965284;5286.180642000487;5301.920471192935;5317.846272259731;5323.9733801330985;5175.40482844473;5177.818776342829;5224.190250511177;5264.6051546134;5270.762815673294;3935.803209839511;3972.582878797728;4080.755154690728;4121.8969525761795;4126.658835878352;4087.202995415895;4089.5705950875026;4118.258713431581;4125.071858831754;4125.174965006996;4050.2102090092444;4052.5947405259385;4055.1078762672164;4068.613481719247;4091.5703851095654;3895.610438956096;3993.5607083220675;4049.9392509112386;4075.266452038632;4088.8160032798296;3627.147620309225;3628.328759179534;3631.310051090311;3639.3267245559655;3659.3596120678776;5170.896582068375;5172.2241663750365;5177.731455793317;5183.403908550527;5208.479152084761;4606.585407313358;4616.607588354994;4643.303504474356;4661.277501987197;4683.180443422388;5388.757505912244;5395.406505284415;5401.4868587484125;5411.9446707891975;5416.539594134503;3972.940405893914;3985.601439855991;3989.7207195496326;3994.2810294147043;4001;5502.614816962814;5644.955683198621;5654;5696.658200507983;5724.513416359614;4039;4048.716589838742;4049.5950404959267;4055.797210139481;4071.145059537471;4123.97938010308;4124.587541245852;4126.793660316972;4126.938095928564;4138.91722165561;4568.269076947672;4671.11248862715;4674.275487299474;4700.764961751899;4715.8210447388155;5458.563314934796;5461.15352120422;5463.535599474009;5465.262189604432;5476.452354764571;1390.5897760160776;1416.9008169428248;1425.4013314407973;1436.346462359624;1437.590286768266;908.8454962656365;915.2952226785471;934.5187228577288;939.2063706168277;943.5518128888723;5186.92219616706;5226.398964119099;5232.581393488512;5237.371515418175;5241.68549887008;5503.862798204985;5514.889702205934;5524.806631767869;5525.944740552623;5530.474604912607;5035.295058691786;5182.896342073137;5184.196449550326;5211.609129315317;5212.166053431432;2882.927926801805;2893.2622181344045;2894.189626904428;2899.594056832045;2912.7961042726806;5677;5679.517241034476;5696.572757043238;5741.555066959791;5758.222639943554;5408.215808707794;5518.613697041249;5527.50252477279;5583.302087239095;5637.661740295595;5671.6597004179885;5675.517581005661;5700.486956173964;5752.36723960364;5762.625429347079;5287.841364759063;5506.1190209566275;5538.113901775697;5596.888062238814;5603.7758489659955;5450.209719590716;5475.178723191553;5484.204790305464;5490.874370754066;5499.50504454601;5671.40450252734;5697.718013446941;5702.714864256854;5731.140328950691;5757.366689664217;3759.3421151298962;3947.3289540777564;3982.6415622594104;4029.979850100731;4051.5948405159247;5558.694271815089;5582.2694964561115;5582.469665381782;5594.776208951759;5598.21624972496;5257.237700533477;5300.973495132654;5424.593155513431;5469.589780766538;5501.257330260363;5478.479544443271;5479.890402192013;5488.615796894179;5492.340919089735;5497.202905578749;5221.347331583552;5400.864978375572;5417.404085550507;5443.374011988672;5448.809291674773;5485.122380419273;5491.341039075177;5501.174823776306;5505;5510.283663123768;5340.866478338073;5377.784888604412;5396;5396.433374495621;5426.375966763872 16:38:24 INFO - PROCESS | 9226 | |5;yui.html;672.9259781424042;715.2632788228124;747.1557140431312;811.1766556944706;816.8366326734653;15374.692506149884;16289.104099274566;16333;16444.17779111044;16468.423605173823;14507.709845803078;14679.706405871877;14680.339384727686;14681.119132852036;14688.926555367208;653.8953767397218;787.6140691061396;800.4716886854677;812.6424373275769;813.8290958898609;781.0198201257442;781.2929298984428;781.7068599275271;783.2950344689797;783.576868491017;838.2413915406536;842.7008412013739;844.1347618690829;845.045099038086;845.6152450634977;684.0764967294137;833.0794472108324;841.8526757817388;854.5556310718454;856.015582080606;623.6694551887507;623.8378021714342;624.950003999679;625.1841347042108;625.2434554189437;14697.118172909659;14777.482788102472;14779.852201477956;14786.556403307917;14794.704105917928;14415.927920360436;14426.855731442656;14673.926630366886;14830.258487075604;14981.325840337211;13548.729025419534;13621.182729036289;13823;13877;14022.793162051446;4443.933340999887;4549;4567.703099298535;4622.422197225347;4714.504976977437;84041.15958840394;84597.57701211516;84908.57545712232;85154;85249.57375213085;10804.78390432187;11237.606683766193;11283.379414132138;11586.246893952035;11602.6519204424;22165.22421715232;22185.112595496;22241.332760017227;22246.88876555607;22342.329730108122;8864.86702699447;8875.822483550422;9045.547722613974;9064.637414503346;9122.315826313054;4598.356230127785;4609.861704148881;4636.907261854744;4651.488336283006;4887.169181239198;3445.3109378124477;3498.4577390504005;3517.402041652874;3525.1539630488496;3534.416821224469;569.8489900176456;569.8546870548066;571.5227784799656;583.5302581421911;593.7387549478302;659.1327724398923;663.8937769956784;682.7234969837224;705.0657878311258;707.5495234768704;9209.447433154031;9491.193248573949;9929.851052234222;10563.313384629975;10602.257841951143;712.2521352579771;788.645109700637;789.2305002622397;807.2169995104856;810.440795850861;4689.601383882408;5061.44324124346;5085.9745701271995;5091.741285577234;5120.692758434506;66812.66593667098;72632.27367726255;120927;122775.38612306882;123314;102453.97546024444;104693;105942.47028764807;107098;107745.46127269471;56287.71856140775;58480.41519584835;68956.6552167246;88349;92138;9232.907670923205;9396.765080873032;9434.292428067785;9445.858312125187;9477.431354118775;50212.748936255084;50650;51408.485915141864;52236.477635224684;52837.20744188917;71369.28630713627;73133.634331828;77648;79237.20762792298;79250;11721.824172637247;12021.879781202426;12231.082668800054;12488.25070495773;12692.238465692088;13976.790348144988;14157;14334.30078892506;14414.783778243545;14807.703845923237;12942.223466592035;13012.349382530647;13096.869031309565;13475.663108422368;13522.459101635824;12696.428660710106;13143;13159.276239806903;13266.668333291358;13322.333883305602;12901.806472902726;12933.870661293267;13439.932800335935;14110.082844615066;14322.355494002586;10267.178625709776;10455.268131230741;10524.31591946521;10864.674059778219;11204.439778010903;9431.62273509052;10709.785804283714;10801.831732064114;11104;11187.608433704781 16:38:24 INFO - PROCESS | 9226 | __end_tp_report 16:38:24 INFO - PROCESS | 9226 | __start_cc_report 16:38:24 INFO - PROCESS | 9226 | _x_x_mozilla_cycle_collect,982 16:38:24 INFO - PROCESS | 9226 | __end_cc_report 16:38:24 INFO - PROCESS | 9226 | __startTimestamp1470181104402__endTimestamp 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | ------- Summary: start ------- 16:38:24 INFO - PROCESS | 9226 | Number of tests: 6 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#0] dojo.html Cycles:165 Average:5318.56 Median:5549.72 stddev:609.88 (11.0%) stddev-sans-first:584.75 16:38:24 INFO - PROCESS | 9226 | Values: 3031.0 3047.6 3056.9 3060.4 3090.6 5228.1 5298.7 5304.8 5400.7 5405.0 5343.2 5370.1 5404.9 5424.1 5437.3 4811.0 4883.2 4903.0 4905.7 5089.1 4988.3 4998.6 5020.1 5031.2 5041.3 4721.2 4840.1 4881.0 4889.0 4939.6 4836.9 4840.5 4851.8 4853.4 4956.0 4833.2 4863.2 4879.6 4884.0 4924.5 5200.5 5201.6 5276.6 5284.7 5368.6 5619.0 5652.5 5653.8 5654.2 5679.5 5676.4 5700.8 5717.1 5732.3 5736.3 5214.5 5308.6 5362.5 5368.8 5420.5 5934.0 5989.4 5989.8 5992.3 5994.0 5686.8 5687.2 5729.3 5734.0 5738.6 5528.2 5663.8 5711.0 5725.9 5815.4 5739.1 5747.0 5748.3 5749.3 5755.3 5697.3 5731.4 5756.5 5770.3 5785.3 4287.8 4326.7 4330.0 4370.8 4376.2 3846.2 3891.2 3920.6 3941.7 3942.5 5338.1 5339.3 5362.6 5386.6 5391.5 5658.1 5685.5 5688.0 5690.6 5691.5 5232.4 5334.6 5343.9 5364.1 5427.3 4878.1 4879.4 4879.7 4882.9 4894.9 5878.0 5878.8 5882.9 5883.1 5933.4 5541.7 5657.7 5659.9 5662.6 5699.8 5870.2 5934.6 5968.7 5983.6 5988.0 5618.5 5622.5 5629.6 5631.8 5656.5 5525.0 5691.3 5696.0 5765.7 5769.6 5603.5 5660.9 5667.0 5670.3 5705.5 5580.8 5616.2 5616.7 5632.7 5653.8 5406.9 5481.8 5582.9 5587.7 5594.6 5596.3 5653.1 5667.8 5713.6 5724.7 5539.9 5543.0 5549.7 5550.4 5554.1 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#1] ext.html Cycles:180 Average:62937.29 Median:44156.17 stddev:124147.74 (281.2%) stddev-sans-first:124407.79 16:38:24 INFO - PROCESS | 9226 | Values: 602.6 621.5 626.3 630.6 636.9 44110.0 45447.5 45496.0 49033.0 49152.5 46765.5 47052.5 47104.0 47207.5 47262.5 8917.4 9126.6 9130.1 9137.7 9138.0 8899.9 8918.4 8964.0 8973.5 8983.2 8993.7 9061.0 9061.0 9065.3 9072.5 8981.5 8983.9 8987.7 9016.7 9022.3 8917.0 8988.2 8997.1 8997.5 9005.1 44764.8 44999.8 45141.0 45145.8 45166.5 3460.4 3583.9 3706.7 3978.2 4220.6 33626.8 34131.1 34339.8 34408.0 35455.3 4219.3 4349.0 4376.4 4464.3 4501.8 377298.0 378442.0 381179.0 381401.0 381572.0 59857.7 59897.4 59942.0 60251.7 60280.7 63996.0 64172.0 64296.7 64410.0 64568.7 13830.8 13961.0 13961.6 13962.4 13981.4 11004.2 11005.1 11008.1 11013.1 11086.9 15818.2 17270.6 17435.9 17553.1 17657.4 5481.7 6171.8 6553.9 6623.9 6639.2 991.5 1032.5 1092.1 1108.7 1171.3 32975.5 33479.5 33914.5 34228.7 34410.5 1071.8 1074.9 1075.6 1114.2 1133.7 16916.9 17548.3 17601.7 17691.8 17784.9 560180.0 563262.0 564574.0 565363.0 565717.1 60718.0 60884.7 60912.4 60969.7 60977.7 1245.3 1330.4 1339.4 1369.2 1382.2 33117.5 34126.7 34750.1 34895.1 34991.7 52862.2 53275.2 53313.5 53341.0 53383.0 439382.0 442027.0 442129.0 442452.0 442607.0 28433.4 29150.1 29349.6 30402.2 30682.4 50198.2 50275.2 50403.2 50985.0 52215.7 41917.8 42578.0 42724.6 42741.6 44720.8 42468.4 42596.0 42888.6 43865.3 43935.6 42704.0 42908.8 43982.1 44202.3 44409.0 35789.5 37861.8 38538.0 38765.6 38897.4 28773.4 28844.0 29026.6 29152.7 29315.8 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#2] jquery.html Cycles:180 Average:29046.28 Median:43899.86 stddev:81327.10 (185.3%) stddev-sans-first:81526.74 16:38:24 INFO - PROCESS | 9226 | Values: 372.3 386.2 403.3 403.6 415.8 14452.8 17945.4 19105.1 20083.5 20103.8 18970.4 19001.4 19048.9 19055.3 19056.5 5651.1 5690.0 5787.8 5813.7 5849.3 5792.9 5812.4 5850.9 5852.7 5856.2 5817.4 5823.4 5839.4 6209.3 6512.7 6584.5 6622.4 6631.6 6636.0 6642.0 5693.9 5822.3 5860.1 5865.5 5867.4 13620.1 13738.5 13745.0 13855.9 13885.0 2145.6 2157.6 2299.6 2316.7 2451.7 9891.0 9940.2 10029.7 10464.6 10516.7 1844.9 1939.6 1955.4 1961.9 1993.4 79850.6 84948.0 86608.0 88121.0 88207.6 10879.1 10934.3 10936.1 10943.5 11121.7 15381.6 15419.2 15439.8 15457.4 15482.5 7581.8 7674.2 7737.9 7809.4 7826.0 7122.0 7122.4 7157.0 7325.1 7340.7 4598.5 4662.5 4700.1 4703.0 4736.6 1895.0 1900.5 1951.9 1965.5 2003.6 951.4 954.2 956.6 991.7 1004.0 18822.2 19744.8 19760.9 19857.7 19998.6 973.3 994.6 994.6 996.6 1063.6 10172.9 10726.7 10852.2 10885.1 10939.0 485666.0 486399.0 490977.0 497393.0 500882.0 92673.0 93131.0 93725.0 93980.5 94194.5 964.7 966.6 978.7 983.8 995.1 16758.0 17220.3 17361.2 18454.4 18502.2 43779.6 44020.2 44417.3 44977.8 45455.0 54816.7 58571.7 58878.0 59083.1 59113.4 8401.7 8430.1 8479.5 8749.7 8868.0 12973.9 13033.2 13137.2 13595.9 13734.8 10476.8 11330.5 11418.6 11557.7 11646.7 10274.5 10828.7 11330.5 11472.7 11646.5 11868.3 11872.9 11893.6 12113.0 12265.4 8538.0 8553.0 8689.3 8769.0 8935.6 10998.9 11496.4 11816.8 12342.0 12345.6 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#3] mootools.html Cycles:180 Average:5995.38 Median:459.77 stddev:3476.59 (756.2%) stddev-sans-first:3468.55 16:38:24 INFO - PROCESS | 9226 | Values: 1314.8 1451.2 1513.7 1622.7 1777.1 8602.5 8990.4 9024.2 9179.9 9212.1 9024.8 9137.1 9138.5 9142.4 9145.7 2305.9 2331.3 2331.9 2335.8 2335.9 2308.8 2399.4 2402.5 2406.4 2407.0 2381.9 2382.4 2390.6 2426.2 2426.3 2314.5 2355.3 2360.4 2361.1 2384.4 2285.5 2312.8 2314.1 2318.4 2321.7 8956.5 9138.6 9188.8 9192.6 9193.9 5412.9 5573.7 5611.2 5611.6 5618.0 3425.4 3441.2 3476.3 3504.0 3508.4 1201.2 1208.1 1213.8 1221.9 1263.3 12930.9 12976.4 13025.5 13028.1 13041.6 4525.5 4634.2 4779.3 4786.4 4820.1 10015.0 10026.2 10031.5 10035.2 10036.6 5276.3 5312.3 5323.0 5324.7 5327.4 4304.3 4824.4 4915.4 4949.1 4983.4 3802.7 3893.9 3961.7 3994.6 4002.1 1121.2 1124.3 1133.7 1152.7 1174.2 901.5 957.0 968.4 970.8 987.7 6026.4 6069.2 6246.0 6377.8 6386.5 454.5 455.3 455.7 459.1 460.4 4278.1 4288.6 4297.1 4349.4 4357.3 11208.6 11511.7 11584.8 11722.3 11735.5 10904.8 11474.0 11558.2 11602.9 11861.7 8463.4 8656.1 8662.4 8665.9 8667.6 5975.4 6113.8 6119.8 6202.9 6256.4 10553.1 10589.0 10639.8 10640.7 10795.9 10453.5 10468.7 10824.9 10825.6 10951.3 7377.3 7611.9 7617.2 8098.6 8330.8 8316.8 8636.1 8869.7 8903.6 9002.1 7664.7 7738.7 7986.2 8264.7 8317.8 7685.9 7764.7 7805.0 7836.0 7858.8 7380.3 7484.4 7500.2 7500.7 7505.0 5366.9 5501.6 5505.8 5585.7 5644.9 5500.9 5630.9 5688.5 5750.0 5765.3 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#4] prototype.html Cycles:180 Average:4594.64 Median:5267.68 stddev:1337.75 (25.4%) stddev-sans-first:1302.54 16:38:24 INFO - PROCESS | 9226 | Values: 324.6 326.3 332.0 336.1 337.3 5275.4 5286.2 5301.9 5317.8 5324.0 5175.4 5177.8 5224.2 5264.6 5270.8 3935.8 3972.6 4080.8 4121.9 4126.7 4087.2 4089.6 4118.3 4125.1 4125.2 4050.2 4052.6 4055.1 4068.6 4091.6 3895.6 3993.6 4049.9 4075.3 4088.8 3627.1 3628.3 3631.3 3639.3 3659.4 5170.9 5172.2 5177.7 5183.4 5208.5 4606.6 4616.6 4643.3 4661.3 4683.2 5388.8 5395.4 5401.5 5411.9 5416.5 3972.9 3985.6 3989.7 3994.3 4001.0 5502.6 5645.0 5654.0 5696.7 5724.5 4039.0 4048.7 4049.6 4055.8 4071.1 4124.0 4124.6 4126.8 4126.9 4138.9 4568.3 4671.1 4674.3 4700.8 4715.8 5458.6 5461.2 5463.5 5465.3 5476.5 1390.6 1416.9 1425.4 1436.3 1437.6 908.8 915.3 934.5 939.2 943.6 5186.9 5226.4 5232.6 5237.4 5241.7 5503.9 5514.9 5524.8 5525.9 5530.5 5035.3 5182.9 5184.2 5211.6 5212.2 2882.9 2893.3 2894.2 2899.6 2912.8 5677.0 5679.5 5696.6 5741.6 5758.2 5408.2 5518.6 5527.5 5583.3 5637.7 5671.7 5675.5 5700.5 5752.4 5762.6 5287.8 5506.1 5538.1 5596.9 5603.8 5450.2 5475.2 5484.2 5490.9 5499.5 5671.4 5697.7 5702.7 5731.1 5757.4 3759.3 3947.3 3982.6 4030.0 4051.6 5558.7 5582.3 5582.5 5594.8 5598.2 5257.2 5301.0 5424.6 5469.6 5501.3 5478.5 5479.9 5488.6 5492.3 5497.2 5221.3 5400.9 5417.4 5443.4 5448.8 5485.1 5491.3 5501.2 5505.0 5510.3 5340.9 5377.8 5396.0 5396.4 5426.4 16:38:24 INFO - PROCESS | 9226 | 16:38:24 INFO - PROCESS | 9226 | [#5] yui.html Cycles:180 Average:20560.86 Median:4644.20 stddev:29532.78 (635.9%) stddev-sans-first:29577.87 16:38:24 INFO - PROCESS | 9226 | Values: 672.9 715.3 747.2 811.2 816.8 15374.7 16289.1 16333.0 16444.2 16468.4 14507.7 14679.7 14680.3 14681.1 14688.9 653.9 787.6 800.5 812.6 813.8 781.0 781.3 781.7 783.3 783.6 838.2 842.7 844.1 845.0 845.6 684.1 833.1 841.9 854.6 856.0 623.7 623.8 625.0 625.2 625.2 14697.1 14777.5 14779.9 14786.6 14794.7 14415.9 14426.9 14673.9 14830.3 14981.3 13548.7 13621.2 13823.0 13877.0 14022.8 4443.9 4549.0 4567.7 4622.4 4714.5 84041.2 84597.6 84908.6 85154.0 85249.6 10804.8 11237.6 11283.4 11586.2 11602.7 22165.2 22185.1 22241.3 22246.9 22342.3 8864.9 8875.8 9045.5 9064.6 9122.3 4598.4 4609.9 4636.9 4651.5 4887.2 3445.3 3498.5 3517.4 3525.2 3534.4 569.8 569.9 571.5 583.5 593.7 659.1 663.9 682.7 705.1 707.5 9209.4 9491.2 9929.9 10563.3 10602.3 712.3 788.6 789.2 807.2 810.4 4689.6 5061.4 5086.0 5091.7 5120.7 66812.7 72632.3 120927.0 122775.4 123314.0 102454.0 104693.0 105942.5 107098.0 107745.5 56287.7 58480.4 68956.7 88349.0 92138.0 9232.9 9396.8 9434.3 9445.9 9477.4 50212.7 50650.0 51408.5 52236.5 52837.2 71369.3 73133.6 77648.0 79237.2 79250.0 11721.8 12021.9 12231.1 12488.3 12692.2 13976.8 14157.0 14334.3 14414.8 14807.7 12942.2 13012.3 13096.9 13475.7 13522.5 12696.4 13143.0 13159.3 13266.7 13322.3 12901.8 12933.9 13439.9 14110.1 14322.4 10267.2 10455.3 10524.3 10864.7 11204.4 9431.6 10709.8 10801.8 11104.0 11187.6 16:38:24 INFO - PROCESS | 9226 | -------- Summary: end -------- 16:38:24 INFO - PROCESS | 9226 | 16:38:25 INFO - TEST-INFO | 9226: exit 0 16:38:25 INFO - TEST-OK | dromaeo_css | took 1115757ms 16:38:25 INFO - TEST-START | kraken 16:38:25 INFO - operating with platform_type : linux_ 16:38:25 INFO - Initialising browser for kraken test... 16:38:25 INFO - TEST-INFO | started process 9419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPF4wQf/profile http://localhost:44879/getInfo.html) 16:38:29 INFO - PROCESS | 9419 | __metrics Screen width/height:1600/1200 16:38:29 INFO - PROCESS | 9419 | colorDepth:24 16:38:29 INFO - PROCESS | 9419 | Browser inner width/height: 1024/697 16:38:29 INFO - PROCESS | 9419 | __metrics 16:38:30 INFO - PROCESS | 9419 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:38:30 INFO - PROCESS | 9419 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:38:30 INFO - PROCESS | 9419 | console.error: PushService: 16:38:30 INFO - PROCESS | 9419 | stateChangeProcessEnqueue: Error transitioning state 16:38:30 INFO - PROCESS | 9419 | UnknownError 16:38:31 INFO - TEST-INFO | 9419: exit 0 16:38:31 INFO - Browser initialized. 16:38:31 INFO - Running cycle 1/1 for kraken test... 16:38:31 INFO - TEST-INFO | started process 9499 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPF4wQf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:43 INFO - PROCESS | 9499 | RSS: Main: 156049408 16:38:43 INFO - PROCESS | 9499 | 16:38:45 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44879/tests/kraken/driver.html?testName=audio-beat-detection) 16:38:46 INFO - PROCESS | 9499 | RSS: Main: 212643840 16:38:46 INFO - PROCESS | 9499 | 16:38:49 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44879/tests/kraken/driver.html?testName=audio-dft) 16:38:49 INFO - PROCESS | 9499 | RSS: Main: 191275008 16:38:49 INFO - PROCESS | 9499 | 16:38:52 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44879/tests/kraken/driver.html?testName=audio-fft) 16:38:53 INFO - PROCESS | 9499 | RSS: Main: 268546048 16:38:53 INFO - PROCESS | 9499 | 16:38:55 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44879/tests/kraken/driver.html?testName=audio-oscillator) 16:38:56 INFO - PROCESS | 9499 | RSS: Main: 179286016 16:38:56 INFO - PROCESS | 9499 | 16:38:58 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44879/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:38:59 INFO - PROCESS | 9499 | RSS: Main: 184225792 16:38:59 INFO - PROCESS | 9499 | 16:39:02 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44879/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:02 INFO - PROCESS | 9499 | RSS: Main: 218918912 16:39:02 INFO - PROCESS | 9499 | 16:39:06 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44879/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:06 INFO - PROCESS | 9499 | RSS: Main: 219541504 16:39:06 INFO - PROCESS | 9499 | 16:39:09 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44879/tests/kraken/driver.html?testName=json-parse-financial) 16:39:10 INFO - PROCESS | 9499 | RSS: Main: 219660288 16:39:10 INFO - PROCESS | 9499 | 16:39:11 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44879/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:12 INFO - PROCESS | 9499 | RSS: Main: 183816192 16:39:12 INFO - PROCESS | 9499 | 16:39:13 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:14 INFO - PROCESS | 9499 | RSS: Main: 188649472 16:39:14 INFO - PROCESS | 9499 | 16:39:16 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:17 INFO - PROCESS | 9499 | RSS: Main: 193867776 16:39:17 INFO - PROCESS | 9499 | 16:39:19 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:20 INFO - PROCESS | 9499 | RSS: Main: 189005824 16:39:20 INFO - PROCESS | 9499 | 16:39:22 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:23 INFO - PROCESS | 9499 | RSS: Main: 192585728 16:39:23 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | Cycle 1(1): loaded http://localhost:44879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44879/tests/kraken/driver.html?testName=ai-astar) 16:39:25 INFO - PROCESS | 9499 | RSS: Main: 242388992 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | __start_tp_report 16:39:25 INFO - PROCESS | 9499 | _x_x_mozilla_page_load 16:39:25 INFO - PROCESS | 9499 | _x_x_mozilla_page_load_details 16:39:25 INFO - PROCESS | 9499 | |i|pagename|runs| 16:39:25 INFO - PROCESS | 9499 | |0;ai-astar;102;112;110;110;113;111;110;111;111;111 16:39:25 INFO - PROCESS | 9499 | |1;audio-beat-detection;142;121;125;178;120;119;139;118;148;125 16:39:25 INFO - PROCESS | 9499 | |2;audio-dft;161;161;162;162;162;160;161;160;160;161 16:39:25 INFO - PROCESS | 9499 | |3;audio-fft;75;75;75;134;76;77;74;74;114;74 16:39:25 INFO - PROCESS | 9499 | |4;audio-oscillator;85;85;112;85;85;84;121;85;84;119 16:39:25 INFO - PROCESS | 9499 | |5;imaging-gaussian-blur;117;117;117;116;117;117;116;116;117;117 16:39:25 INFO - PROCESS | 9499 | |6;imaging-darkroom;145;146;145;145;146;145;145;145;146;145 16:39:25 INFO - PROCESS | 9499 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;96;95 16:39:25 INFO - PROCESS | 9499 | |8;json-parse-financial;52;49;49;50;49;51;51;49;49;49 16:39:25 INFO - PROCESS | 9499 | |9;json-stringify-tinderbox;63;62;62;62;62;62;62;62;63;62 16:39:25 INFO - PROCESS | 9499 | |10;stanford-crypto-aes;75;75;76;75;75;75;75;75;84;77 16:39:25 INFO - PROCESS | 9499 | |11;stanford-crypto-ccm;122;121;119;120;130;121;120;129;158;119 16:39:25 INFO - PROCESS | 9499 | |12;stanford-crypto-pbkdf2;159;156;155;156;157;157;155;155;160;155 16:39:25 INFO - PROCESS | 9499 | |13;stanford-crypto-sha256-iterative;63;62;62;62;70;63;64;61;63;61 16:39:25 INFO - PROCESS | 9499 | __end_tp_report 16:39:25 INFO - PROCESS | 9499 | __start_cc_report 16:39:25 INFO - PROCESS | 9499 | _x_x_mozilla_cycle_collect,1661 16:39:25 INFO - PROCESS | 9499 | __end_cc_report 16:39:25 INFO - PROCESS | 9499 | __startTimestamp1470181165428__endTimestamp 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | ------- Summary: start ------- 16:39:25 INFO - PROCESS | 9499 | Number of tests: 14 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:3.00 (2.7%) stddev-sans-first:1.00 16:39:25 INFO - PROCESS | 9499 | Values: 102.0 112.0 110.0 110.0 113.0 111.0 110.0 111.0 111.0 111.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:132.00 stddev:18.93 (14.3%) stddev-sans-first:19.83 16:39:25 INFO - PROCESS | 9499 | Values: 142.0 121.0 125.0 178.0 120.0 119.0 139.0 118.0 148.0 125.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 16:39:25 INFO - PROCESS | 9499 | Values: 161.0 161.0 162.0 162.0 162.0 160.0 161.0 160.0 160.0 161.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#3] audio-fft Cycles:10 Average:84.80 Median:75.00 stddev:21.21 (28.3%) stddev-sans-first:22.20 16:39:25 INFO - PROCESS | 9499 | Values: 75.0 75.0 75.0 134.0 76.0 77.0 74.0 74.0 114.0 74.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:85.00 stddev:15.92 (18.7%) stddev-sans-first:16.51 16:39:25 INFO - PROCESS | 9499 | Values: 85.0 85.0 112.0 85.0 85.0 84.0 121.0 85.0 84.0 119.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 16:39:25 INFO - PROCESS | 9499 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 16:39:25 INFO - PROCESS | 9499 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 145.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:39:25 INFO - PROCESS | 9499 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:49.50 stddev:1.14 (2.3%) stddev-sans-first:0.88 16:39:25 INFO - PROCESS | 9499 | Values: 52.0 49.0 49.0 50.0 49.0 51.0 51.0 49.0 49.0 49.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 16:39:25 INFO - PROCESS | 9499 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 62.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.00 stddev:2.82 (3.8%) stddev-sans-first:2.96 16:39:25 INFO - PROCESS | 9499 | Values: 75.0 75.0 76.0 75.0 75.0 75.0 75.0 75.0 84.0 77.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:121.50 stddev:11.95 (9.8%) stddev-sans-first:12.59 16:39:25 INFO - PROCESS | 9499 | Values: 122.0 121.0 119.0 120.0 130.0 121.0 120.0 129.0 158.0 119.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.50 Median:156.50 stddev:1.78 (1.1%) stddev-sans-first:1.64 16:39:25 INFO - PROCESS | 9499 | Values: 159.0 156.0 155.0 156.0 157.0 157.0 155.0 155.0 160.0 155.0 16:39:25 INFO - PROCESS | 9499 | 16:39:25 INFO - PROCESS | 9499 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:2.60 (4.1%) stddev-sans-first:2.76 16:39:25 INFO - PROCESS | 9499 | Values: 63.0 62.0 62.0 62.0 70.0 63.0 64.0 61.0 63.0 61.0 16:39:25 INFO - PROCESS | 9499 | -------- Summary: end -------- 16:39:25 INFO - PROCESS | 9499 | 16:39:26 INFO - TEST-INFO | 9499: exit 0 16:39:26 INFO - TEST-OK | kraken | took 61256ms 16:39:26 INFO - SUITE-END | took 1177s 16:39:26 INFO - Completed test suite (00:19:37) 16:39:26 INFO - javascript benchmark 16:39:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3030.9696903030977, 3047.603811504505, 3056.8930087446915, 3060.433819743347, 3090.6291245050597, 5228.137357336044, 5298.655587386822, 5304.814331498398, 5400.675959442431, 5405.0, 5343.198520221959, 5370.060239458089, 5404.891902161964, 5424.050791111559, 5437.265969094171, 4811.037792441508, 4883.1698611236, 4902.97548512257, 4905.681130726509, 5089.058524173021, 4988.301637770697, 4998.625103117262, 5020.071286811952, 5031.2453132030305, 5041.319421878036, 4721.173794585934, 4840.080384726926, 4880.951190488086, 4889.046635906, 4939.58013568847, 4836.927446088312, 4840.467548569654, 4851.805927762885, 4853.39332583427, 4955.950440495585, 4833.178359678863, 4863.221884498464, 4879.585235255007, 4883.9511604883855, 4924.507549245082, 5200.479952004807, 5201.609879259033, 5276.551493123089, 5284.682919024871, 5368.570514358842, 5619.044762390404, 5652.5195358394585, 5653.773849046033, 5654.151877218409, 5679.488846003879, 5676.432356764331, 5700.800471983461, 5717.085266357363, 5732.254806875079, 5736.282964629421, 5214.5046220609365, 5308.575313974873, 5362.4637536246455, 5368.758405871745, 5420.457954204627, 5934.0, 5989.371116032841, 5989.760409583655, 5992.280926288786, 5994.0, 5686.800961966312, 5687.203791469197, 5729.312482502127, 5733.971330143323, 5738.598298119092, 5528.226048353234, 5663.773449062074, 5710.97202503545, 5725.914111288334, 5815.389384114692, 5739.139129130664, 5747.0, 5748.281464816898, 5749.281339832521, 5755.280589926259, 5697.316322041299, 5731.369549349568, 5756.453136951983, 5770.336411312753, 5785.305763308345, 4287.8499252526635, 4326.6538676905775, 4330.047389574289, 4370.847020354336, 4376.212281789245, 3846.1538461538416, 3891.2217556488818, 3920.6079392061138, 3941.6846652268323, 3942.48747662802, 5338.065838478251, 5339.305890234323, 5362.597805164551, 5386.59600529954, 5391.541718953933, 5658.122990936329, 5685.516731077904, 5688.033034384164, 5690.601657883991, 5691.544676425876, 5232.372115346183, 5334.5732341412595, 5343.86640333987, 5364.114921038063, 5427.267318911975, 4878.121938051185, 4879.365682461328, 4879.658423910292, 4882.877928051827, 4894.926576101361, 5878.0, 5878.794242201502, 5882.911756323659, 5883.058710606282, 5933.406659334032, 5541.695206763667, 5657.6888271143835, 5659.915101273402, 5662.603617746883, 5699.800506982236, 5870.236869206976, 5934.584579079423, 5968.731407086605, 5983.55123365758, 5988.0, 5618.4662457066515, 5622.493975542221, 5629.605927585029, 5631.774729010794, 5656.547476201977, 5525.0, 5691.260136182435, 5695.971520142539, 5765.654060756368, 5769.596128271149, 5603.467670571289, 5660.88678226425, 5667.036603777286, 5670.319561652628, 5705.486506214461, 5580.832575022756, 5616.157576363578, 5616.66300022, 5632.718364081861, 5653.802116925888, 5406.864828379322, 5481.75332110048, 5582.888342233214, 5587.664740115606, 5594.552435805044, 5596.328440586993, 5653.067243904709, 5667.801626943037, 5713.571482138773, 5724.74238659253, 5539.916901246565, 5542.972285138549, 5549.722513874371, 5550.361708403424, 5554.083576210041], "value": 5276.692097862286, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.605293532736, 621.4748537485824, 626.3329554024966, 630.5523078614184, 636.891728406171, 44110.0, 45447.545524544745, 45495.999999999956, 49033.0, 49152.50847491524, 46765.53234467654, 47052.52947470524, 47104.0, 47207.52792472074, 47262.52737472624, 8917.37578369516, 9126.58930348136, 9130.086991300883, 9137.68018119366, 9138.0, 8899.866502002475, 8918.375713700043, 8963.955180224122, 8973.46159230445, 8983.19151276385, 8993.730188094334, 9060.954695226515, 9061.048589898031, 9065.274778017776, 9072.455652660861, 8981.506017169053, 8983.910160898438, 8987.685431009948, 9016.729498115066, 9022.278217742565, 8916.955415222947, 8988.191062804313, 8997.100289971017, 8997.460152390877, 9005.054469280696, 44764.77617611924, 44999.77500112511, 45141.0, 45145.774271128765, 45166.54833451656, 3460.411730005905, 3583.874564390234, 3706.684931780802, 3978.224246271949, 4220.620144187038, 33626.83186584076, 34131.14672133192, 34339.828300858586, 34408.0, 35455.29089418223, 4219.346001369762, 4348.978255108705, 4376.387305777162, 4464.330350447426, 4501.774911254424, 377298.0, 378442.0, 381179.0, 381401.0, 381572.0, 59857.70071149617, 59897.40102599005, 59942.0, 60251.698741506014, 60280.698596506736, 63996.0, 64172.0, 64296.67851660712, 64410.0, 64568.677156613914, 13830.792538111937, 13961.0, 13961.581152565439, 13962.371693273826, 13981.370838312301, 11004.174686898514, 11005.119590432907, 11008.064314533194, 11013.118950484102, 11086.944565277123, 15818.209089545708, 17270.568235794206, 17435.912820435817, 17553.122343882755, 17657.38199163023, 5481.698506582096, 6171.759826091248, 6553.934460655332, 6623.9006414903815, 6639.170103737033, 991.5389343954952, 1032.5198782565992, 1092.0990183098975, 1108.6840250528721, 1171.2503997441645, 32975.50536741951, 33479.497807532905, 33914.49128263078, 34228.65771342305, 34410.48384274238, 1071.8124328242527, 1074.9301295415773, 1075.5590208014676, 1114.2311804854623, 1133.6769020829024, 16916.91541542309, 17548.27834105942, 17601.7359739604, 17691.823081769275, 17784.91107544454, 560180.0, 563262.0, 564574.0, 565363.0, 565717.0569765827, 60718.0, 60884.69557652184, 60912.39087609156, 60969.73211875215, 60977.69511152416, 1245.315076707807, 1330.401319406271, 1339.370495866941, 1369.1716511510392, 1382.1845111384332, 33117.503237450976, 34126.65873341235, 34750.13124671904, 34895.12762180966, 34991.65008349986, 52862.20706689326, 53275.20087198618, 53313.46686533085, 53341.0, 53383.0, 439382.0, 442027.0, 442129.0, 442452.0, 442607.0, 28433.43133137284, 29150.12549623515, 29349.559756604096, 30402.239944001576, 30682.386352273275, 50198.247026295365, 50275.24587131269, 50403.243951340024, 50985.0, 52215.73892130515, 41917.79041104897, 42578.0, 42724.572754272056, 42741.572584275, 44720.77639611781, 42468.362974556025, 42596.0, 42888.57111428846, 43865.34201986909, 43935.56064439315, 42704.0, 42908.78545607252, 43982.12035759331, 44202.3369649462, 44409.0, 35789.46315805317, 37861.81069094747, 38538.0, 38765.6123438762, 38897.41653875138, 28773.42453150967, 28844.0, 29026.564601531416, 29152.70847291585, 29315.80131490104], "value": 20534.12957041463, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.2629194195491, 386.20249185432095, 403.25599269348055, 403.6105158522026, 415.8024938154377, 14452.783208251885, 17945.371911983082, 19105.14026868791, 20083.497912552193, 20103.798962010376, 18970.430887073377, 19001.429957101274, 19048.904755476236, 19055.33306334275, 19056.523586910334, 5651.124075768253, 5690.004249256384, 5787.826365209051, 5813.73838177281, 5849.298084229898, 5792.884142317151, 5812.360640329561, 5850.853728656775, 5852.736626851802, 5856.150858125569, 5817.389174136718, 5823.359430462646, 5839.386864379243, 6209.348018458042, 6512.674366281691, 6584.539082264243, 6622.403983641495, 6631.602103873759, 6636.0, 6641.966790166042, 5693.886122277573, 5822.330432000332, 5860.062390017577, 5865.472107510344, 5867.413258674141, 13620.114692544952, 13738.450461981509, 13745.0, 13855.930720346434, 13885.0, 2145.6137895178863, 2157.6439887418555, 2299.5745787029455, 2316.652502124678, 2451.6935383077116, 9891.0, 9940.15508681763, 10029.749256268578, 10464.633737819217, 10516.737081572945, 1844.9169787359601, 1939.5539026023953, 1955.3840540229783, 1961.8822870627807, 1993.4418362858423, 79850.60074699647, 84947.99999999939, 86608.0, 88121.0, 88207.55896220542, 10879.12966962641, 10934.289271197347, 10936.125109991182, 10943.453892073958, 11121.721956951142, 15381.615459613598, 15419.229038548028, 15439.768403473958, 15457.381704731684, 15482.5355239343, 7581.848363032708, 7674.194209608022, 7737.92262077372, 7809.3752499799875, 7825.960870195612, 7122.0, 7122.394596459359, 7157.0, 7325.084364454443, 7340.706371745177, 4598.540145985442, 4662.510436404196, 4700.130475861976, 4702.95297047032, 4736.573708366263, 1894.9810501894804, 1900.5058684741791, 1951.945949187423, 1965.49879780655, 2003.5692326149656, 951.414879848888, 954.1775209350246, 956.6460409648336, 991.6727479931606, 1003.9949800250952, 18822.24711011544, 19744.802551974582, 19760.90119549393, 19857.702134467996, 19998.600027999648, 973.2894986659636, 994.5773046455228, 994.6071301835734, 996.5565323431149, 1063.5639387850945, 10172.898271017195, 10726.678199654023, 10852.186086043579, 10885.129189664807, 10939.0, 485666.0, 486399.0, 490977.0, 497393.0, 500882.0, 92673.0, 93131.0, 93725.0, 93980.53009734908, 94194.52902735442, 964.6816550538306, 966.6133546581283, 978.7308490165119, 983.8229118758549, 995.0944640377367, 16758.0, 17220.311187552357, 17361.218745156246, 18454.35409760652, 18502.167402466654, 43779.562204378824, 44020.15778264452, 44417.333739994574, 44977.77511112423, 45455.0, 54816.72591637016, 58571.70714146402, 58878.0, 59083.11375329288, 59113.40886591251, 8401.747947561584, 8430.114837941928, 8479.491230526188, 8749.65001399937, 8867.955660221658, 12973.870261297267, 13033.229791951466, 13137.211767293993, 13595.864041359855, 13734.793978090136, 10476.790464190826, 11330.490127944098, 11418.629764428322, 11557.653270401564, 11646.650600481998, 10274.486275686037, 10828.729281768019, 11330.490127944428, 11472.655820325404, 11646.475908584303, 11868.287902725864, 11872.940635296769, 11893.643190704293, 12113.0, 12265.352926186839, 8537.957310213409, 8553.0, 8689.30485561141, 8768.99156596974, 8935.553222338987, 10998.890011099787, 11496.367699776592, 11816.76366472683, 12342.0, 12345.567905123278], "value": 9122.823739834015, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.8093526438672, 1451.151076620177, 1513.727529044772, 1622.675464907018, 1777.0581591756375, 8602.526861022641, 8990.37067405282, 9024.232940200074, 9179.862302065474, 9212.078792120785, 9024.774380640487, 9137.131972462623, 9138.543072846347, 9142.40574362666, 9145.725628231146, 2305.9077636894503, 2331.3006098170567, 2331.895064722092, 2335.7781010803947, 2335.92992210234, 2308.803751681109, 2399.4121440247163, 2402.459446624513, 2406.4465173010217, 2407.0492155598618, 2381.880905954704, 2382.4282172278704, 2390.605550084234, 2426.2478631624163, 2426.296374051523, 2314.5486630107134, 2355.2934119764086, 2360.4334959609655, 2361.0791791201445, 2384.3562238195764, 2285.520040791436, 2312.8034117100065, 2314.0859360552486, 2318.420394901275, 2321.7097862767155, 8956.462612243286, 9138.634454621808, 9188.770280742969, 9192.632294708204, 9193.862092068624, 5412.864678383072, 5573.665580065209, 5611.158326251054, 5611.635243709186, 5618.0449323614675, 3425.366307233145, 3441.15691655546, 3476.28736109097, 3504.0363899927725, 3508.386032444312, 1201.2492192379764, 1208.1482554798845, 1213.8422005139364, 1221.8961388282007, 1263.3178083834719, 12930.870691293061, 12976.35118244084, 13025.478980840755, 13028.088033837636, 13041.608751737558, 4525.52481989393, 4634.21218392874, 4779.259214821708, 4786.377770889762, 4820.084184005098, 10015.048570385801, 10026.248031397528, 10031.548580313904, 10035.197184225244, 10036.64871729486, 5276.314079169683, 5312.256284120226, 5323.0, 5324.707141107198, 5327.38735045467, 4304.311310190355, 4824.421069471686, 4915.36100306965, 4949.084419382426, 4983.401991760939, 3802.6957843372466, 3893.9415908761393, 3961.6632586230494, 3994.6404823565435, 4002.0995276063236, 1121.2039451989017, 1124.2973141786383, 1133.7392399748105, 1152.7175841918615, 1174.2015429507846, 901.4771432569095, 956.9569369378395, 968.3995922527937, 970.752458123174, 987.7301525186037, 6026.397360263939, 6069.2110025696375, 6246.0, 6377.840553986095, 6386.457151142205, 454.54771014147497, 455.3374839608362, 455.67191622032084, 459.10932790386545, 460.43596594171873, 4278.122984788111, 4288.592583704543, 4297.140571885635, 4349.412829268008, 4357.346398040319, 11208.607698730506, 11511.712207194887, 11584.76830463386, 11722.296662200295, 11735.530578776925, 10904.78190436187, 11474.0, 11558.190926635054, 11602.94198529002, 11861.703457413461, 8463.407561470638, 8656.134386561294, 8662.393632445668, 8665.913340866511, 8667.609957551926, 5975.37258587842, 6113.755449781959, 6119.755209791559, 6202.937970620236, 6256.436920677161, 10553.102986246255, 10589.046985771318, 10639.840402394126, 10640.733981650521, 10795.892041079489, 10453.529591168568, 10468.738281543023, 10824.945875270574, 10825.621103261348, 10951.34291942486, 7377.299156580117, 7611.858221266854, 7617.200193979553, 8098.595070246582, 8330.833383332178, 8316.83366332682, 8636.050034496326, 8869.73390798277, 8903.643854245758, 9002.144796244347, 7664.655090520829, 7738.729144479917, 7986.241307075818, 8264.669413223404, 8317.750467485985, 7685.884711729217, 7764.650590723544, 7804.9609751950875, 7836.0, 7858.803529911798, 7380.261973802577, 7484.43866709988, 7500.212477689764, 7500.737474188377, 7504.962475187589, 5366.892662146814, 5501.642393244426, 5505.752241149238, 5585.748641311232, 5644.915326270191, 5500.862478437911, 5630.887382252414, 5688.459596338506, 5749.9712501437225, 5765.250517432707], "value": 4675.126241821336, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.58939441606367, 326.2854348975743, 331.9917002074948, 336.13110110364704, 337.27822459935743, 5275.366955965284, 5286.180642000487, 5301.920471192935, 5317.846272259731, 5323.9733801330985, 5175.40482844473, 5177.818776342829, 5224.190250511177, 5264.6051546134, 5270.762815673294, 3935.803209839511, 3972.582878797728, 4080.755154690728, 4121.8969525761795, 4126.658835878352, 4087.202995415895, 4089.5705950875026, 4118.258713431581, 4125.071858831754, 4125.174965006996, 4050.2102090092444, 4052.5947405259385, 4055.1078762672164, 4068.613481719247, 4091.5703851095654, 3895.610438956096, 3993.5607083220675, 4049.9392509112386, 4075.266452038632, 4088.8160032798296, 3627.147620309225, 3628.328759179534, 3631.310051090311, 3639.3267245559655, 3659.3596120678776, 5170.896582068375, 5172.2241663750365, 5177.731455793317, 5183.403908550527, 5208.479152084761, 4606.585407313358, 4616.607588354994, 4643.303504474356, 4661.277501987197, 4683.180443422388, 5388.757505912244, 5395.406505284415, 5401.4868587484125, 5411.9446707891975, 5416.539594134503, 3972.940405893914, 3985.601439855991, 3989.7207195496326, 3994.2810294147043, 4001.0, 5502.614816962814, 5644.955683198621, 5654.0, 5696.658200507983, 5724.513416359614, 4039.0, 4048.716589838742, 4049.5950404959267, 4055.797210139481, 4071.145059537471, 4123.97938010308, 4124.587541245852, 4126.793660316972, 4126.938095928564, 4138.91722165561, 4568.269076947672, 4671.11248862715, 4674.275487299474, 4700.764961751899, 4715.8210447388155, 5458.563314934796, 5461.15352120422, 5463.535599474009, 5465.262189604432, 5476.452354764571, 1390.5897760160776, 1416.9008169428248, 1425.4013314407973, 1436.346462359624, 1437.590286768266, 908.8454962656365, 915.2952226785471, 934.5187228577288, 939.2063706168277, 943.5518128888723, 5186.92219616706, 5226.398964119099, 5232.581393488512, 5237.371515418175, 5241.68549887008, 5503.862798204985, 5514.889702205934, 5524.806631767869, 5525.944740552623, 5530.474604912607, 5035.295058691786, 5182.896342073137, 5184.196449550326, 5211.609129315317, 5212.166053431432, 2882.927926801805, 2893.2622181344045, 2894.189626904428, 2899.594056832045, 2912.7961042726806, 5677.0, 5679.517241034476, 5696.572757043238, 5741.555066959791, 5758.222639943554, 5408.215808707794, 5518.613697041249, 5527.50252477279, 5583.302087239095, 5637.661740295595, 5671.6597004179885, 5675.517581005661, 5700.486956173964, 5752.36723960364, 5762.625429347079, 5287.841364759063, 5506.1190209566275, 5538.113901775697, 5596.888062238814, 5603.7758489659955, 5450.209719590716, 5475.178723191553, 5484.204790305464, 5490.874370754066, 5499.50504454601, 5671.40450252734, 5697.718013446941, 5702.714864256854, 5731.140328950691, 5757.366689664217, 3759.3421151298962, 3947.3289540777564, 3982.6415622594104, 4029.979850100731, 4051.5948405159247, 5558.694271815089, 5582.2694964561115, 5582.469665381782, 5594.776208951759, 5598.21624972496, 5257.237700533477, 5300.973495132654, 5424.593155513431, 5469.589780766538, 5501.257330260363, 5478.479544443271, 5479.890402192013, 5488.615796894179, 5492.340919089735, 5497.202905578749, 5221.347331583552, 5400.864978375572, 5417.404085550507, 5443.374011988672, 5448.809291674773, 5485.122380419273, 5491.341039075177, 5501.174823776306, 5505.0, 5510.283663123768, 5340.866478338073, 5377.784888604412, 5396.0, 5396.433374495621, 5426.375966763872], "value": 4169.06454201015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.9259781424042, 715.2632788228124, 747.1557140431312, 811.1766556944706, 816.8366326734653, 15374.692506149884, 16289.104099274566, 16333.0, 16444.17779111044, 16468.423605173823, 14507.709845803078, 14679.706405871877, 14680.339384727686, 14681.119132852036, 14688.926555367208, 653.8953767397218, 787.6140691061396, 800.4716886854677, 812.6424373275769, 813.8290958898609, 781.0198201257442, 781.2929298984428, 781.7068599275271, 783.2950344689797, 783.576868491017, 838.2413915406536, 842.7008412013739, 844.1347618690829, 845.045099038086, 845.6152450634977, 684.0764967294137, 833.0794472108324, 841.8526757817388, 854.5556310718454, 856.015582080606, 623.6694551887507, 623.8378021714342, 624.950003999679, 625.1841347042108, 625.2434554189437, 14697.118172909659, 14777.482788102472, 14779.852201477956, 14786.556403307917, 14794.704105917928, 14415.927920360436, 14426.855731442656, 14673.926630366886, 14830.258487075604, 14981.325840337211, 13548.729025419534, 13621.182729036289, 13823.0, 13877.0, 14022.793162051446, 4443.933340999887, 4549.0, 4567.703099298535, 4622.422197225347, 4714.504976977437, 84041.15958840394, 84597.57701211516, 84908.57545712232, 85154.0, 85249.57375213085, 10804.78390432187, 11237.606683766193, 11283.379414132138, 11586.246893952035, 11602.6519204424, 22165.22421715232, 22185.112595496, 22241.332760017227, 22246.88876555607, 22342.329730108122, 8864.86702699447, 8875.822483550422, 9045.547722613974, 9064.637414503346, 9122.315826313054, 4598.356230127785, 4609.861704148881, 4636.907261854744, 4651.488336283006, 4887.169181239198, 3445.3109378124477, 3498.4577390504005, 3517.402041652874, 3525.1539630488496, 3534.416821224469, 569.8489900176456, 569.8546870548066, 571.5227784799656, 583.5302581421911, 593.7387549478302, 659.1327724398923, 663.8937769956784, 682.7234969837224, 705.0657878311258, 707.5495234768704, 9209.447433154031, 9491.193248573949, 9929.851052234222, 10563.313384629975, 10602.257841951143, 712.2521352579771, 788.645109700637, 789.2305002622397, 807.2169995104856, 810.440795850861, 4689.601383882408, 5061.44324124346, 5085.9745701271995, 5091.741285577234, 5120.692758434506, 66812.66593667098, 72632.27367726255, 120927.0, 122775.38612306882, 123314.0, 102453.97546024444, 104693.0, 105942.47028764807, 107098.0, 107745.46127269471, 56287.71856140775, 58480.41519584835, 68956.6552167246, 88349.0, 92138.0, 9232.907670923205, 9396.765080873032, 9434.292428067785, 9445.858312125187, 9477.431354118775, 50212.748936255084, 50650.0, 51408.485915141864, 52236.477635224684, 52837.20744188917, 71369.28630713627, 73133.634331828, 77648.0, 79237.20762792298, 79250.0, 11721.824172637247, 12021.879781202426, 12231.082668800054, 12488.25070495773, 12692.238465692088, 13976.790348144988, 14157.0, 14334.30078892506, 14414.783778243545, 14807.703845923237, 12942.223466592035, 13012.349382530647, 13096.869031309565, 13475.663108422368, 13522.459101635824, 12696.428660710106, 13143.0, 13159.276239806903, 13266.668333291358, 13322.333883305602, 12901.806472902726, 12933.870661293267, 13439.932800335935, 14110.082844615066, 14322.355494002586, 10267.178625709776, 10455.268131230741, 10524.31591946521, 10864.674059778219, 11204.439778010903, 9431.62273509052, 10709.785804283714, 10801.831732064114, 11104.0, 11187.608433704781], "value": 7555.328308131063, "unit": "score"}], "name": "dromaeo_css", "value": 7252.980233511574, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 110.0, 110.0, 113.0, 111.0, 110.0, 111.0, 111.0, 111.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 121.0, 125.0, 178.0, 120.0, 119.0, 139.0, 118.0, 148.0, 125.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 162.0, 160.0, 161.0, 160.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 134.0, 76.0, 77.0, 74.0, 74.0, 114.0, 74.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 112.0, 85.0, 85.0, 84.0, 121.0, 85.0, 84.0, 119.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 50.0, 49.0, 51.0, 51.0, 49.0, 49.0, 49.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 75.0, 75.0, 75.0, 75.0, 75.0, 84.0, 77.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 119.0, 120.0, 130.0, 121.0, 120.0, 129.0, 158.0, 119.0], "value": 125.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 156.0, 155.0, 156.0, 157.0, 157.0, 155.0, 155.0, 160.0, 155.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 70.0, 63.0, 64.0, 61.0, 63.0, 61.0], "value": 63.1, "unit": "score"}], "name": "kraken", "value": 1474.8000000000002, "alertThreshold": 2.0}]} 16:39:26 INFO - Return code: 0 16:39:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:39:26 INFO - # TBPL SUCCESS # 16:39:26 INFO - Running post-action listener: _resource_record_post_action 16:39:26 INFO - [mozharness: 2016-08-02 23:39:26.746154Z] Finished run-tests step (success) 16:39:26 INFO - Running post-run listener: _resource_record_post_run 16:39:26 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 221184; Write bytes: 295641088; Read time: 52; Write time: 246288 16:39:26 INFO - TinderboxPrint: CPU usage
13.0% 16:39:26 INFO - TinderboxPrint: I/O read bytes / time
221,184 / 52 16:39:26 INFO - TinderboxPrint: I/O write bytes / time
295,641,088 / 246,288 16:39:26 INFO - TinderboxPrint: CPU idle
8,291.4 (87.0%) 16:39:26 INFO - TinderboxPrint: CPU user
1,193.0 (12.5%) 16:39:26 INFO - TinderboxPrint: Swap in / out
0 / 0 16:39:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 5541888; Read time: 0; Write time: 296 16:39:26 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 217088; Write bytes: 289669120; Read time: 40; Write time: 245904 16:39:27 INFO - Running post-run listener: _upload_blobber_files 16:39:27 INFO - Blob upload gear active. 16:39:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:27 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 16:39:27 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'] 16:39:27 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 16:39:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:31 INFO - (blobuploader) - INFO - Done attempting. 16:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:31 INFO - (blobuploader) - INFO - Done attempting. 16:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:32 INFO - (blobuploader) - INFO - Done attempting. 16:39:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:32 INFO - Return code: 0 16:39:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d1d6a3dbef669458f8aa4a8273754215701dce2eb7dfcf4303aca0f910535d2747ee44933b065b1cf95e227103d4403c247e8b6b8ba0ca22b167308fd7d0c463", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/aa7a1fa598f3246aa62deec9905cb604027d4a284c634ad63e65029c35e4fdf1e5e699f50c9ebb5b82143060ccce53648aa567769387a0b737ba2f1c6e44a49d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8746c4c60d2692e10c5f09296ca9eecd1f9f3bf99a08a8656a245b932d89f0a922584117d28e702dcbea18beb44bc91d909628dbdbbf6641733dc2e96c1f9895"} 16:39:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:32 INFO - Contents: 16:39:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d1d6a3dbef669458f8aa4a8273754215701dce2eb7dfcf4303aca0f910535d2747ee44933b065b1cf95e227103d4403c247e8b6b8ba0ca22b167308fd7d0c463", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/aa7a1fa598f3246aa62deec9905cb604027d4a284c634ad63e65029c35e4fdf1e5e699f50c9ebb5b82143060ccce53648aa567769387a0b737ba2f1c6e44a49d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8746c4c60d2692e10c5f09296ca9eecd1f9f3bf99a08a8656a245b932d89f0a922584117d28e702dcbea18beb44bc91d909628dbdbbf6641733dc2e96c1f9895"} 16:39:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:32 INFO - Copying logs to upload dir... 16:39:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.831436 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-08-02 16:39:32.515711) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:32.519683) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d1d6a3dbef669458f8aa4a8273754215701dce2eb7dfcf4303aca0f910535d2747ee44933b065b1cf95e227103d4403c247e8b6b8ba0ca22b167308fd7d0c463", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/aa7a1fa598f3246aa62deec9905cb604027d4a284c634ad63e65029c35e4fdf1e5e699f50c9ebb5b82143060ccce53648aa567769387a0b737ba2f1c6e44a49d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8746c4c60d2692e10c5f09296ca9eecd1f9f3bf99a08a8656a245b932d89f0a922584117d28e702dcbea18beb44bc91d909628dbdbbf6641733dc2e96c1f9895"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470169830/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d1d6a3dbef669458f8aa4a8273754215701dce2eb7dfcf4303aca0f910535d2747ee44933b065b1cf95e227103d4403c247e8b6b8ba0ca22b167308fd7d0c463", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/aa7a1fa598f3246aa62deec9905cb604027d4a284c634ad63e65029c35e4fdf1e5e699f50c9ebb5b82143060ccce53648aa567769387a0b737ba2f1c6e44a49d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8746c4c60d2692e10c5f09296ca9eecd1f9f3bf99a08a8656a245b932d89f0a922584117d28e702dcbea18beb44bc91d909628dbdbbf6641733dc2e96c1f9895"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:32.545540) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:32.545889) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1470178936.791602-586265027 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 16:39:32.628436) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 16:39:32.628789) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 16:39:32.629135) ========= ========= Total master_lag: 0.15 =========