builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1470137941.43 results: success (0) buildid: 20160802043528 builduid: ffc435c3d47548569cf32faf4f2fc7f1 revision: 8fd9da290db715fdf37b760dcb09e17f802c14d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.431794) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.432839) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.433490) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.449843) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.450476) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 04:39:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.5M=0s 2016-08-02 04:39:02 (42.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104642 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.570734) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.571097) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018970 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.623139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:01.623891) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8fd9da290db715fdf37b760dcb09e17f802c14d0 --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 8fd9da290db715fdf37b760dcb09e17f802c14d0 --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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 04:39:02,638 truncating revision to first 12 chars 2016-08-02 04:39:02,638 Setting DEBUG logging. 2016-08-02 04:39:02,639 attempt 1/10 2016-08-02 04:39:02,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/8fd9da290db7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 04:39:03,102 unpacking tar archive at: mozilla-inbound-8fd9da290db7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.788658 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:02.428565) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:02.428911) ========= script_repo_revision: 8fd9da290db715fdf37b760dcb09e17f802c14d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:02.429380) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:02.429685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 04:39:02.440883) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 04:39:02.441210) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:39:03 INFO - MultiFileLogger online at 20160802 04:39:03 in /builds/slave/test 04:39:03 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 04:39:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:39:03 INFO - {'append_to_log': False, 04:39:03 INFO - 'base_work_dir': '/builds/slave/test', 04:39:03 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:39:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:39:03 INFO - 'branch': 'Mozilla-Inbound', 04:39:03 INFO - 'buildbot_json_path': 'buildprops.json', 04:39:03 INFO - 'config_files': ('talos/linux_config.py',), 04:39:03 INFO - 'default_actions': ('clobber', 04:39:03 INFO - 'read-buildbot-config', 04:39:03 INFO - 'download-and-extract', 04:39:03 INFO - 'populate-webroot', 04:39:03 INFO - 'create-virtualenv', 04:39:03 INFO - 'install', 04:39:03 INFO - 'run-tests'), 04:39:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:39:03 INFO - 'download_minidump_stackwalk': True, 04:39:03 INFO - 'download_symbols': 'ondemand', 04:39:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:39:03 INFO - 'tooltool.py': '/tools/tooltool.py', 04:39:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:39:03 INFO - '/tools/misc-python/virtualenv.py')}, 04:39:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:39:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:39:03 INFO - 'installer_path': 'installer.exe', 04:39:03 INFO - 'log_level': 'info', 04:39:03 INFO - 'log_name': 'talos', 04:39:03 INFO - 'log_to_console': True, 04:39:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:39:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:39:03 INFO - 'opt_config_files': (), 04:39:03 INFO - 'pip_index': False, 04:39:03 INFO - 'sps_profile': False, 04:39:03 INFO - 'sps_profile_interval': 0, 04:39:03 INFO - 'suite': 'dromaeojs', 04:39:03 INFO - 'system_bits': '32', 04:39:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:39:03 INFO - 'title': 'talos-linux64-ix-064', 04:39:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:39:03 INFO - 'use_talos_json': True, 04:39:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:39:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:39:03 INFO - 'work_dir': 'build'} 04:39:03 INFO - [mozharness: 2016-08-02 11:39:03.550278Z] Running clobber step. 04:39:03 INFO - Running pre-action listener: _resource_record_pre_action 04:39:03 INFO - Running main action method: clobber 04:39:03 INFO - rmtree: /builds/slave/test/build 04:39:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:39:04 INFO - Running post-action listener: _resource_record_post_action 04:39:04 INFO - [mozharness: 2016-08-02 11:39:04.008439Z] Finished clobber step (success) 04:39:04 INFO - [mozharness: 2016-08-02 11:39:04.008540Z] Running read-buildbot-config step. 04:39:04 INFO - Running pre-action listener: _resource_record_pre_action 04:39:04 INFO - Running main action method: read_buildbot_config 04:39:04 INFO - Using buildbot properties: 04:39:04 INFO - { 04:39:04 INFO - "project": "", 04:39:04 INFO - "product": "firefox", 04:39:04 INFO - "script_repo_revision": "production", 04:39:04 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:39:04 INFO - "repository": "", 04:39:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:39:04 INFO - "buildid": "20160802043528", 04:39:04 INFO - "stage_platform": "linux64-pgo", 04:39:04 INFO - "basedir": "/builds/slave/test", 04:39:04 INFO - "buildnumber": 1335, 04:39:04 INFO - "platform": "ubuntu64_hw", 04:39:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:39:04 INFO - "slavebuilddir": "test", 04:39:04 INFO - "branch": "mozilla-inbound", 04:39:04 INFO - "revision": "8fd9da290db715fdf37b760dcb09e17f802c14d0", 04:39:04 INFO - "slavename": "talos-linux64-ix-064", 04:39:04 INFO - "builduid": "ffc435c3d47548569cf32faf4f2fc7f1", 04:39:04 INFO - "repo_path": "integration/mozilla-inbound" 04:39:04 INFO - } 04:39:04 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 04:39:04 INFO - Found a test packages url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 04:39:04 INFO - Running post-action listener: _resource_record_post_action 04:39:04 INFO - [mozharness: 2016-08-02 11:39:04.010930Z] Finished read-buildbot-config step (success) 04:39:04 INFO - [mozharness: 2016-08-02 11:39:04.011019Z] Running download-and-extract step. 04:39:04 INFO - Running pre-action listener: _resource_record_pre_action 04:39:04 INFO - Running main action method: download_and_extract 04:39:04 INFO - mkdir: /builds/slave/test/build/tests 04:39:04 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')]} 04:39:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:39:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:04 INFO - Downloaded 1361 bytes. 04:39:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 04:39:04 INFO - Using the following test package requirements: 04:39:04 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:39:04 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:39:04 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:39:04 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'jsshell-linux-x86_64.zip'], 04:39:04 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:39:04 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 04:39:04 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:39:04 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:39:04 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:39:04 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 04:39:04 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:39:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:39:04 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')]} 04:39:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:39:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:04 INFO - Downloaded 21809851 bytes. 04:39: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'] 04:39: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 04:39:05 INFO - Return code: 0 04:39:05 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 04:39:05 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')]} 04:39:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 04:39:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:05 INFO - Downloaded 21809851 bytes. 04:39:05 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'] 04:39:05 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 04:39:07 INFO - Return code: 0 04:39:07 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')]} 04:39:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 04:39:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:07 INFO - Downloaded 14413723 bytes. 04:39:07 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'] 04:39:07 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 04:39:07 INFO - Return code: 0 04:39:07 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')]} 04:39:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:39:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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 04:39:09 INFO - Downloaded 59383190 bytes. 04:39:09 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:39:09 INFO - mkdir: /builds/slave/test/properties 04:39:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:09 INFO - Contents: 04:39:09 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 04:39:09 INFO - Running post-action listener: _resource_record_post_action 04:39:09 INFO - Running post-action listener: set_extra_try_arguments 04:39:09 INFO - [mozharness: 2016-08-02 11:39:09.364604Z] Finished download-and-extract step (success) 04:39:09 INFO - [mozharness: 2016-08-02 11:39:09.364838Z] Running populate-webroot step. 04:39:09 INFO - Running pre-action listener: _resource_record_pre_action 04:39:09 INFO - Running main action method: populate_webroot 04:39:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:09 INFO - '--tppagecycles', 04:39:09 INFO - '7'], 04:39:09 INFO - 'tests': ['tsvgm']}}, 04:39:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['damp', 'tps']}, 04:39:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'tests': ['damp', 'tps']}, 04:39:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['dromaeo_dom']}, 04:39:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['basic_compositor_video']}, 04:39:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:39:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['a11yr', 04:39:09 INFO - 'ts_paint', 04:39:09 INFO - 'tpaint', 04:39:09 INFO - 'sessionrestore', 04:39:09 INFO - 'sessionrestore_no_auto_restore', 04:39:09 INFO - 'tabpaint']}, 04:39:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['tsvgx', 04:39:09 INFO - 'tsvgr_opacity', 04:39:09 INFO - 'tart', 04:39:09 INFO - 'tscrollx', 04:39:09 INFO - 'cart']}, 04:39:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:09 INFO - 'tsvgr_opacity', 04:39:09 INFO - 'tart', 04:39:09 INFO - 'tscrollx', 04:39:09 INFO - 'cart']}, 04:39:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'talos_options': ['--disable-e10s'], 04:39:09 INFO - 'tests': ['tp5o']}, 04:39:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'tests': ['tp5o']}, 04:39:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'talos_options': ['--disable-e10s', 04:39:09 INFO - '--xperf_path', 04:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:09 INFO - 'tests': ['tp5n']}, 04:39:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:39:09 INFO - 'talos_options': ['--xperf_path', 04:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:09 INFO - 'tests': ['tp5n']}}, 04:39:09 INFO - 'talos.zip': {'path': '', 04:39:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:09 INFO - Running post-action listener: _resource_record_post_action 04:39:09 INFO - [mozharness: 2016-08-02 11:39:09.381052Z] Finished populate-webroot step (success) 04:39:09 INFO - [mozharness: 2016-08-02 11:39:09.381141Z] Running create-virtualenv step. 04:39:09 INFO - Running pre-action listener: _resource_record_pre_action 04:39:09 INFO - Running main action method: create_virtualenv 04:39:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39: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 04:39:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:09 INFO - Using real prefix '/usr' 04:39:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:09 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:11 INFO - Installing pip.................done. 04:39:11 INFO - Return code: 0 04:39:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:11 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')]} 04:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 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')]} 04:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:11 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:11 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 04:39:11 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 04:39:11 INFO - Using env: {'DISPLAY': ':0', 04:39:11 INFO - 'HOME': '/home/cltbld', 04:39:11 INFO - 'LANG': 'en_US.UTF-8', 04:39:11 INFO - 'LANGUAGE': 'en_US:en', 04:39:11 INFO - 'LOGNAME': 'cltbld', 04:39:11 INFO - 'MAIL': '/var/mail/cltbld', 04:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:11 INFO - 'MOZ_NO_REMOTE': '1', 04:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:11 INFO - 'NO_EM_RESTART': '1', 04:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:11 INFO - 'PWD': '/builds/slave/test', 04:39:11 INFO - 'SHELL': '/bin/bash', 04:39:11 INFO - 'SHLVL': '1', 04:39:11 INFO - 'TERM': 'linux', 04:39:11 INFO - 'TMOUT': '86400', 04:39:11 INFO - 'USER': 'cltbld', 04:39:11 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:11 INFO - '_': '/tools/buildbot/bin/python'} 04:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39: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 04:39:12 INFO - Cleaning up... 04:39:12 INFO - Return code: 0 04:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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')]} 04:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39: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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39: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 04:39: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 04:39:12 INFO - Using env: {'DISPLAY': ':0', 04:39:12 INFO - 'HOME': '/home/cltbld', 04:39:12 INFO - 'LANG': 'en_US.UTF-8', 04:39:12 INFO - 'LANGUAGE': 'en_US:en', 04:39:12 INFO - 'LOGNAME': 'cltbld', 04:39:12 INFO - 'MAIL': '/var/mail/cltbld', 04:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:12 INFO - 'MOZ_NO_REMOTE': '1', 04:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:12 INFO - 'NO_EM_RESTART': '1', 04:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:12 INFO - 'PWD': '/builds/slave/test', 04:39:12 INFO - 'SHELL': '/bin/bash', 04:39:12 INFO - 'SHLVL': '1', 04:39:12 INFO - 'TERM': 'linux', 04:39:12 INFO - 'TMOUT': '86400', 04:39:12 INFO - 'USER': 'cltbld', 04:39:12 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:12 INFO - '_': '/tools/buildbot/bin/python'} 04:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:12 INFO - Downloading/unpacking psutil>=3.1.1 04:39:12 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 04:39:12 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 04:39:12 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 04:39:12 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 04:39:12 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 04:39:12 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 04:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:14 INFO - Installing collected packages: psutil 04:39:14 INFO - Running setup.py install for psutil 04:39:14 INFO - building 'psutil._psutil_linux' extension 04:39:14 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 04:39:14 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 04:39:14 INFO - building 'psutil._psutil_posix' extension 04:39:14 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 04:39:14 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 04:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:14 INFO - Successfully installed psutil 04:39:14 INFO - Cleaning up... 04:39:14 INFO - Return code: 0 04:39:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:14 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:14 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 04:39:14 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 04:39:14 INFO - Using env: {'DISPLAY': ':0', 04:39:14 INFO - 'HOME': '/home/cltbld', 04:39:14 INFO - 'LANG': 'en_US.UTF-8', 04:39:14 INFO - 'LANGUAGE': 'en_US:en', 04:39:14 INFO - 'LOGNAME': 'cltbld', 04:39:14 INFO - 'MAIL': '/var/mail/cltbld', 04:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:14 INFO - 'MOZ_NO_REMOTE': '1', 04:39:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:14 INFO - 'NO_EM_RESTART': '1', 04:39:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:14 INFO - 'PWD': '/builds/slave/test', 04:39:14 INFO - 'SHELL': '/bin/bash', 04:39:14 INFO - 'SHLVL': '1', 04:39:14 INFO - 'TERM': 'linux', 04:39:14 INFO - 'TMOUT': '86400', 04:39:14 INFO - 'USER': 'cltbld', 04:39:14 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:14 INFO - '_': '/tools/buildbot/bin/python'} 04:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:17 INFO - Installing collected packages: mozsystemmonitor 04:39:17 INFO - Running setup.py install for mozsystemmonitor 04:39:17 INFO - Successfully installed mozsystemmonitor 04:39:17 INFO - Cleaning up... 04:39:17 INFO - Return code: 0 04:39:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:39:17 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')]} 04:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:17 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')]} 04:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:17 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:17 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 04:39:17 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 04:39:17 INFO - Using env: {'DISPLAY': ':0', 04:39:17 INFO - 'HOME': '/home/cltbld', 04:39:17 INFO - 'LANG': 'en_US.UTF-8', 04:39:17 INFO - 'LANGUAGE': 'en_US:en', 04:39:17 INFO - 'LOGNAME': 'cltbld', 04:39:17 INFO - 'MAIL': '/var/mail/cltbld', 04:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:17 INFO - 'MOZ_NO_REMOTE': '1', 04:39:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:17 INFO - 'NO_EM_RESTART': '1', 04:39:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:17 INFO - 'PWD': '/builds/slave/test', 04:39:17 INFO - 'SHELL': '/bin/bash', 04:39:17 INFO - 'SHLVL': '1', 04:39:17 INFO - 'TERM': 'linux', 04:39:17 INFO - 'TMOUT': '86400', 04:39:17 INFO - 'USER': 'cltbld', 04:39:17 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:17 INFO - '_': '/tools/buildbot/bin/python'} 04:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:17 INFO - Downloading/unpacking jsonschema==2.5.1 04:39:17 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 04:39:17 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 04:39:17 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 04:39:17 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 04:39:17 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 04:39:17 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 04:39:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 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 04:39:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:19 INFO - warning: no files found matching '*.txt' 04:39:19 INFO - no previously-included directories found matching 'build' 04:39:19 INFO - no previously-included directories found matching 'dist' 04:39:19 INFO - no previously-included directories found matching '.git*' 04:39:19 INFO - Installing collected packages: jsonschema, functools32 04:39:19 INFO - Running setup.py install for functools32 04:39:19 INFO - warning: no files found matching '*.txt' 04:39:19 INFO - no previously-included directories found matching 'build' 04:39:19 INFO - no previously-included directories found matching 'dist' 04:39:19 INFO - no previously-included directories found matching '.git*' 04:39:19 INFO - Successfully installed jsonschema functools32 04:39:19 INFO - Cleaning up... 04:39:19 INFO - Return code: 0 04:39:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:19 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 04:39:19 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 04:39:19 INFO - Using env: {'DISPLAY': ':0', 04:39:19 INFO - 'HOME': '/home/cltbld', 04:39:19 INFO - 'LANG': 'en_US.UTF-8', 04:39:19 INFO - 'LANGUAGE': 'en_US:en', 04:39:19 INFO - 'LOGNAME': 'cltbld', 04:39:19 INFO - 'MAIL': '/var/mail/cltbld', 04:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:19 INFO - 'MOZ_NO_REMOTE': '1', 04:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:19 INFO - 'NO_EM_RESTART': '1', 04:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:19 INFO - 'PWD': '/builds/slave/test', 04:39:19 INFO - 'SHELL': '/bin/bash', 04:39:19 INFO - 'SHLVL': '1', 04:39:19 INFO - 'TERM': 'linux', 04:39:19 INFO - 'TMOUT': '86400', 04:39:19 INFO - 'USER': 'cltbld', 04:39:19 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:19 INFO - '_': '/tools/buildbot/bin/python'} 04:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:39:19 INFO - Cleaning up... 04:39:19 INFO - Return code: 0 04:39:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:19 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:39: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 blobuploader==1.2.4 04:39:20 INFO - Using env: {'DISPLAY': ':0', 04:39:20 INFO - 'HOME': '/home/cltbld', 04:39:20 INFO - 'LANG': 'en_US.UTF-8', 04:39:20 INFO - 'LANGUAGE': 'en_US:en', 04:39:20 INFO - 'LOGNAME': 'cltbld', 04:39:20 INFO - 'MAIL': '/var/mail/cltbld', 04:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:20 INFO - 'MOZ_NO_REMOTE': '1', 04:39:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:20 INFO - 'NO_EM_RESTART': '1', 04:39:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:20 INFO - 'PWD': '/builds/slave/test', 04:39:20 INFO - 'SHELL': '/bin/bash', 04:39:20 INFO - 'SHLVL': '1', 04:39:20 INFO - 'TERM': 'linux', 04:39:20 INFO - 'TMOUT': '86400', 04:39:20 INFO - 'USER': 'cltbld', 04:39:20 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:20 INFO - '_': '/tools/buildbot/bin/python'} 04:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39: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 04:39:22 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 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 04:39:22 INFO - Downloading docopt-0.6.1.tar.gz 04:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:22 INFO - Running setup.py install for blobuploader 04:39:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:22 INFO - Running setup.py install for requests 04:39:23 INFO - Running setup.py install for docopt 04:39:23 INFO - Successfully installed blobuploader requests docopt 04:39:23 INFO - Cleaning up... 04:39:23 INFO - Return code: 0 04:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:23 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:23 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 04:39:23 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 04:39:23 INFO - Using env: {'DISPLAY': ':0', 04:39:23 INFO - 'HOME': '/home/cltbld', 04:39:23 INFO - 'LANG': 'en_US.UTF-8', 04:39:23 INFO - 'LANGUAGE': 'en_US:en', 04:39:23 INFO - 'LOGNAME': 'cltbld', 04:39:23 INFO - 'MAIL': '/var/mail/cltbld', 04:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:23 INFO - 'MOZ_NO_REMOTE': '1', 04:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:23 INFO - 'NO_EM_RESTART': '1', 04:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:23 INFO - 'PWD': '/builds/slave/test', 04:39:23 INFO - 'SHELL': '/bin/bash', 04:39:23 INFO - 'SHLVL': '1', 04:39:23 INFO - 'TERM': 'linux', 04:39:23 INFO - 'TMOUT': '86400', 04:39:23 INFO - 'USER': 'cltbld', 04:39:23 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:23 INFO - '_': '/tools/buildbot/bin/python'} 04:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:23 INFO - Running setup.py (path:/tmp/pip-osagjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:23 INFO - Running setup.py (path:/tmp/pip-oJvfiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:23 INFO - Running setup.py (path:/tmp/pip-1mX2_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:23 INFO - Running setup.py (path:/tmp/pip-fKshb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:24 INFO - Running setup.py (path:/tmp/pip-i6BLQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:24 INFO - Running setup.py (path:/tmp/pip-jgrRXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:24 INFO - Running setup.py (path:/tmp/pip-2nzapi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:24 INFO - Running setup.py (path:/tmp/pip-ukycnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:24 INFO - Running setup.py (path:/tmp/pip-zwoxq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:24 INFO - Running setup.py (path:/tmp/pip-mQBfoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:24 INFO - Running setup.py (path:/tmp/pip-kcJJTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:24 INFO - Running setup.py (path:/tmp/pip-g3kejV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:24 INFO - Running setup.py (path:/tmp/pip-uJ4D6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:24 INFO - Running setup.py (path:/tmp/pip-Jc_g32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:24 INFO - Running setup.py (path:/tmp/pip-eNDfVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:24 INFO - Running setup.py (path:/tmp/pip-yEqWSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:24 INFO - Running setup.py (path:/tmp/pip-gPzjP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:24 INFO - Running setup.py install for manifestparser 04:39:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozcrash 04:39:25 INFO - Running setup.py install for mozdebug 04:39:25 INFO - Running setup.py install for mozdevice 04:39:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozfile 04:39:25 INFO - Running setup.py install for mozhttpd 04:39:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozinfo 04:39:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozInstall 04:39:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozleak 04:39:25 INFO - Running setup.py install for mozlog 04:39:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for moznetwork 04:39:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozprocess 04:39:26 INFO - Running setup.py install for mozprofile 04:39:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozrunner 04:39:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozscreenshot 04:39:26 INFO - Running setup.py install for moztest 04:39:26 INFO - Running setup.py install for mozversion 04:39:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:26 INFO - Cleaning up... 04:39:26 INFO - Return code: 0 04:39:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:26 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')]} 04:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:26 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')]} 04:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:26 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:26 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 04:39:26 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 04:39:26 INFO - Using env: {'DISPLAY': ':0', 04:39:26 INFO - 'HOME': '/home/cltbld', 04:39:26 INFO - 'LANG': 'en_US.UTF-8', 04:39:26 INFO - 'LANGUAGE': 'en_US:en', 04:39:26 INFO - 'LOGNAME': 'cltbld', 04:39:26 INFO - 'MAIL': '/var/mail/cltbld', 04:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:26 INFO - 'MOZ_NO_REMOTE': '1', 04:39:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:26 INFO - 'NO_EM_RESTART': '1', 04:39:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:26 INFO - 'PWD': '/builds/slave/test', 04:39:26 INFO - 'SHELL': '/bin/bash', 04:39:26 INFO - 'SHLVL': '1', 04:39:26 INFO - 'TERM': 'linux', 04:39:26 INFO - 'TMOUT': '86400', 04:39:26 INFO - 'USER': 'cltbld', 04:39:26 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:26 INFO - '_': '/tools/buildbot/bin/python'} 04:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:26 INFO - Running setup.py (path:/tmp/pip-dgN2UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:27 INFO - Running setup.py (path:/tmp/pip-H8Pf6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:27 INFO - Running setup.py (path:/tmp/pip-JkPAaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:27 INFO - Running setup.py (path:/tmp/pip-LpV9Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:27 INFO - Running setup.py (path:/tmp/pip-io1iPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:27 INFO - Running setup.py (path:/tmp/pip-aA9r_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:27 INFO - Running setup.py (path:/tmp/pip-WTMQxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:27 INFO - Running setup.py (path:/tmp/pip-bROqbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:27 INFO - Running setup.py (path:/tmp/pip-boktd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:27 INFO - Running setup.py (path:/tmp/pip-faJ66A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:27 INFO - Running setup.py (path:/tmp/pip-lh7Y5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:27 INFO - Running setup.py (path:/tmp/pip-NgcOAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:27 INFO - Running setup.py (path:/tmp/pip-BavzH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:27 INFO - Running setup.py (path:/tmp/pip-b1ttcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:27 INFO - Running setup.py (path:/tmp/pip-sTOc_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:28 INFO - Running setup.py (path:/tmp/pip-_OMUZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:28 INFO - Running setup.py (path:/tmp/pip-xAu8VE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:30 INFO - Downloading blessings-1.6.tar.gz 04:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:39:30 INFO - Installing collected packages: blessings 04:39:30 INFO - Running setup.py install for blessings 04:39:30 INFO - Successfully installed blessings 04:39:30 INFO - Cleaning up... 04:39:30 INFO - Return code: 0 04:39:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:39:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:30 INFO - Reading from file tmpfile_stdout 04:39:30 INFO - Current package versions: 04:39:30 INFO - argparse == 1.2.1 04:39:30 INFO - blessings == 1.6 04:39:30 INFO - blobuploader == 1.2.4 04:39:30 INFO - docopt == 0.6.1 04:39:30 INFO - functools32 == 3.2.3-2 04:39:30 INFO - jsonschema == 2.5.1 04:39:30 INFO - manifestparser == 1.1 04:39:30 INFO - mozInstall == 1.12 04:39:30 INFO - mozcrash == 0.17 04:39:30 INFO - mozdebug == 0.1 04:39:30 INFO - mozdevice == 0.48 04:39:30 INFO - mozfile == 1.2 04:39:30 INFO - mozhttpd == 0.7 04:39:30 INFO - mozinfo == 0.9 04:39:30 INFO - mozleak == 0.1 04:39:30 INFO - mozlog == 3.3 04:39:30 INFO - moznetwork == 0.27 04:39:30 INFO - mozprocess == 0.23 04:39:30 INFO - mozprofile == 0.28 04:39:30 INFO - mozrunner == 6.12 04:39:30 INFO - mozscreenshot == 0.1 04:39:30 INFO - mozsystemmonitor == 0.3 04:39:30 INFO - moztest == 0.7 04:39:30 INFO - mozversion == 1.4 04:39:30 INFO - psutil == 3.1.1 04:39:30 INFO - requests == 1.2.3 04:39:30 INFO - wsgiref == 0.1.2 04:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:30 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')]} 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:30 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')]} 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:30 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:30 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 04:39:30 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 04:39:30 INFO - Using env: {'DISPLAY': ':0', 04:39:30 INFO - 'HOME': '/home/cltbld', 04:39:30 INFO - 'LANG': 'en_US.UTF-8', 04:39:30 INFO - 'LANGUAGE': 'en_US:en', 04:39:30 INFO - 'LOGNAME': 'cltbld', 04:39:30 INFO - 'MAIL': '/var/mail/cltbld', 04:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:30 INFO - 'MOZ_NO_REMOTE': '1', 04:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:30 INFO - 'NO_EM_RESTART': '1', 04:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:30 INFO - 'PWD': '/builds/slave/test', 04:39:30 INFO - 'SHELL': '/bin/bash', 04:39:30 INFO - 'SHLVL': '1', 04:39:30 INFO - 'TERM': 'linux', 04:39:30 INFO - 'TMOUT': '86400', 04:39:30 INFO - 'USER': 'cltbld', 04:39:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:30 INFO - '_': '/tools/buildbot/bin/python'} 04:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 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)) 04:39:30 INFO - Cleaning up... 04:39:30 INFO - Return code: 0 04:39:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:39:30 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')]} 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:30 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')]} 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:39:30 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 0x18f2030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', '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 04:39:30 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 04:39:30 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 04:39:30 INFO - Using env: {'DISPLAY': ':0', 04:39:30 INFO - 'HOME': '/home/cltbld', 04:39:30 INFO - 'LANG': 'en_US.UTF-8', 04:39:30 INFO - 'LANGUAGE': 'en_US:en', 04:39:30 INFO - 'LOGNAME': 'cltbld', 04:39:30 INFO - 'MAIL': '/var/mail/cltbld', 04:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:30 INFO - 'MOZ_NO_REMOTE': '1', 04:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:30 INFO - 'NO_EM_RESTART': '1', 04:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:30 INFO - 'PWD': '/builds/slave/test', 04:39:30 INFO - 'SHELL': '/bin/bash', 04:39:30 INFO - 'SHLVL': '1', 04:39:30 INFO - 'TERM': 'linux', 04:39:30 INFO - 'TMOUT': '86400', 04:39:30 INFO - 'USER': 'cltbld', 04:39:30 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:30 INFO - '_': '/tools/buildbot/bin/python'} 04:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Running post-action listener: _resource_record_post_action 04:39:31 INFO - Running post-action listener: _start_resource_monitoring 04:39:31 INFO - Starting resource monitoring. 04:39:31 INFO - [mozharness: 2016-08-02 11:39:31.060156Z] Finished create-virtualenv step (success) 04:39:31 INFO - [mozharness: 2016-08-02 11:39:31.060640Z] Running install step. 04:39:31 INFO - Running pre-action listener: _resource_record_pre_action 04:39:31 INFO - Running main action method: install 04:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:31 INFO - Reading from file tmpfile_stdout 04:39:31 INFO - Detecting whether we're running mozinstall >=1.0... 04:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:39:31 INFO - Reading from file tmpfile_stdout 04:39:31 INFO - Output received: 04:39:31 INFO - Usage: mozinstall [options] installer 04:39:31 INFO - Options: 04:39:31 INFO - -h, --help show this help message and exit 04:39:31 INFO - -d DEST, --destination=DEST 04:39:31 INFO - Directory to install application into. [default: 04:39:31 INFO - "/builds/slave/test"] 04:39:31 INFO - --app=APP Application being installed. [default: firefox] 04:39:31 INFO - mkdir: /builds/slave/test/build/application 04:39:31 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'] 04:39:31 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 04:39:48 INFO - Reading from file tmpfile_stdout 04:39:48 INFO - Output received: 04:39:48 INFO - /builds/slave/test/build/application/firefox/firefox 04:39:48 INFO - Running post-action listener: _resource_record_post_action 04:39:48 INFO - [mozharness: 2016-08-02 11:39:48.233943Z] Finished install step (success) 04:39:48 INFO - [mozharness: 2016-08-02 11:39:48.234204Z] Running run-tests step. 04:39:48 INFO - Running pre-action listener: _resource_record_pre_action 04:39:48 INFO - Running main action method: run_tests 04:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:39:48 INFO - Python 2.7.3 04:39:48 INFO - Return code: 0 04:39:48 INFO - grabbing minidump binary from tooltool 04:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:39:48 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 0x1bb13b0>, '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 0x1bb22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:39:48 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 04:39:48 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 04:39:48 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 04:39:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:39:48 INFO - Return code: 0 04:39:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:39:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:39:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:39:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:39:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:39:48 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/1470126604/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 04:39:48 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/1470126604/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --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 04:39:48 INFO - Using env: {'DISPLAY': ':0', 04:39:48 INFO - 'HOME': '/home/cltbld', 04:39:48 INFO - 'LANG': 'en_US.UTF-8', 04:39:48 INFO - 'LANGUAGE': 'en_US:en', 04:39:48 INFO - 'LOGNAME': 'cltbld', 04:39:48 INFO - 'MAIL': '/var/mail/cltbld', 04:39:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:39:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:48 INFO - 'MOZ_NO_REMOTE': '1', 04:39:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:39:48 INFO - 'NO_EM_RESTART': '1', 04:39:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:48 INFO - 'PWD': '/builds/slave/test', 04:39:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:39:48 INFO - 'SHELL': '/bin/bash', 04:39:48 INFO - 'SHLVL': '1', 04:39:48 INFO - 'TERM': 'linux', 04:39:48 INFO - 'TMOUT': '86400', 04:39:48 INFO - 'USER': 'cltbld', 04:39:48 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173', 04:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:48 INFO - '_': '/tools/buildbot/bin/python'} 04:39:48 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/1470126604/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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 04:39:48 INFO - mozversion application_buildid: 20160802013004 04:39:48 INFO - mozversion application_changeset: 8fd9da290db715fdf37b760dcb09e17f802c14d0 04:39:48 INFO - mozversion application_display_name: Nightly 04:39:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:39:48 INFO - mozversion application_name: Firefox 04:39:48 INFO - mozversion application_remotingname: firefox 04:39:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:39:48 INFO - mozversion application_vendor: Mozilla 04:39:48 INFO - mozversion application_version: 51.0a1 04:39:48 INFO - mozversion platform_buildid: 20160802013004 04:39:48 INFO - mozversion platform_changeset: 8fd9da290db715fdf37b760dcb09e17f802c14d0 04:39:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:39:48 INFO - mozversion platform_version: 51.0a1 04:39:48 INFO - using testdate: 1470137988 04:39:48 INFO - actual date: 1470137988 04:39:48 INFO - starting webserver on 'localhost:58504' 04:39:48 INFO - SUITE-START | Running 2 tests 04:39:48 INFO - TEST-START | dromaeo_css 04:39:48 INFO - operating with platform_type : linux_ 04:39:48 INFO - Initialising browser for dromaeo_css test... 04:39:48 INFO - TEST-INFO | started process 25051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqZwymk/profile http://localhost:58504/getInfo.html) 04:39:53 INFO - PROCESS | 25051 | __metrics Screen width/height:1600/1200 04:39:53 INFO - PROCESS | 25051 | colorDepth:24 04:39:53 INFO - PROCESS | 25051 | Browser inner width/height: 1024/697 04:39:53 INFO - PROCESS | 25051 | __metrics 04:39:54 INFO - PROCESS | 25051 | 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" 04:39:54 INFO - PROCESS | 25051 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:39:54 INFO - TEST-INFO | 25051: exit 0 04:39:54 INFO - Browser initialized. 04:39:54 INFO - Running cycle 1/1 for dromaeo_css test... 04:39:55 INFO - TEST-INFO | started process 25131 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqZwymk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:40:06 INFO - PROCESS | 25131 | RSS: Main: 155394048 04:40:06 INFO - PROCESS | 25131 | 04:40:57 INFO - PROCESS | 25131 | 1470138057765 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] 04:43:06 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58504/tests/dromaeo/cssquery-ext.html) 04:43:08 INFO - PROCESS | 25131 | RSS: Main: 257531904 04:43:08 INFO - PROCESS | 25131 | 04:46:10 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-ext.html (next: http://localhost:58504/tests/dromaeo/cssquery-jquery.html) 04:46:11 INFO - PROCESS | 25131 | RSS: Main: 238694400 04:46:11 INFO - PROCESS | 25131 | 04:49:13 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58504/tests/dromaeo/cssquery-mootools.html) 04:49:13 INFO - PROCESS | 25131 | RSS: Main: 221384704 04:49:13 INFO - PROCESS | 25131 | 04:52:15 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58504/tests/dromaeo/cssquery-prototype.html) 04:52:16 INFO - PROCESS | 25131 | RSS: Main: 212602880 04:52:16 INFO - PROCESS | 25131 | 04:55:18 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58504/tests/dromaeo/cssquery-yui.html) 04:55:19 INFO - PROCESS | 25131 | RSS: Main: 186421248 04:55:19 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | Cycle 1(1): loaded http://localhost:58504/tests/dromaeo/cssquery-yui.html (next: http://localhost:58504/tests/dromaeo/cssquery-dojo.html) 04:58:22 INFO - PROCESS | 25131 | RSS: Main: 273989632 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | __start_tp_report 04:58:22 INFO - PROCESS | 25131 | _x_x_mozilla_page_load 04:58:22 INFO - PROCESS | 25131 | _x_x_mozilla_page_load_details 04:58:22 INFO - PROCESS | 25131 | |i|pagename|runs| 04:58:22 INFO - PROCESS | 25131 | |0;dojo.html;3087.3362227121165;3087.8764849406025;3101.348716769479;3124.343887783565;3148.4018036573048;5307.840764777063;5388.191771234312;5424.755885985141;5445.537129344;5459.344878614567;5394.622376433651;5445.428230035848;5476.67139971601;5575;5586.720663966806;4847.927281090787;4860.027994401125;4890.437599676029;4959.057779021974;4960.305557221992;4996.975015124919;5028.120078986181;5071.290019397287;5072.163093089635;5077.847664570068;4963.70217786934;5015.523525265094;5023.0707319145895;5029.597632189434;5038.093143234217;4807.903841923142;4868.269759536098;4887.633427492952;4916.311716359677;4964.627652926045;4848;4857.781399837016;4865.026994601094;4875.366202393701;4999.575036121934;5383.623146379755;5418.810341638024;5423.511883930465;5473.124300111963;5484;5616.32604087508;5672.092465205547;5794.217780599607;5796.101604251347;5869.2369991901205;5858.56060795442;5872.559558033122;5922.8815423691285;5922.96348139074;5931.822045338647;5257.263983042377;5490.313710786152;5497.780088796403;5497.917531236994;5498.615096943216;5871.647701137946;5959.374265702126;5959.761609535657;6044;6045.879082418327;5705.429457054344;5729.541636669056;5755.6834374108985;5757.683327417032;5764.798232061858;5594.636348637326;5828.446297601721;5834.445727655866;5841.211436456109;5850.736716847752;5413.7834486620095;5695.288088988877;5754.309482862083;5812.476877081083;5861.824145275648;5790.507806836466;5834.824955251349;5840.2991641003355;5842.503387212135;5846.736896839652;4292.978535107304;4443.400140980991;4445.310976798602;4452.487963884195;4492.842750503782;3913.0608653922855;3958.722889397772;3977.8209980550946;3977.940330895039;4035.9798201008807;5552;5554.527865131509;5559.27729395176;5569.777208911679;5612.102063669793;5726.341470730836;5889.764409423575;5910.261217347832;5912.260967379078;5944.138099975479;5332.706701131475;5447.972760136174;5451.727413629303;5469.2616496772425;5497.835064948058;4954.677945933503;4960.578350840219;4965.677230979974;4968.850934471971;5008.12357837377;5895;6036.426539478743;6042.305134909454;6051.485623721945;6055.1220073090035;5794;5867.471927526543;5869.853253668522;5890.4993075587345;5949.553783466344;6120.44915957566;6124.969375153096;6127.050307202391;6127.662978536223;6135.233095863017;5606.691631960118;5727.31272247333;5777.3356064051495;5780.855478613068;5786.855328616818;5766.971165144148;5806.65160090396;5807.970960145341;5820.883582328414;5904.084866845646;5691.8861622768145;5700.971495142498;5702.714864256687;5702.914456283076;5707.828765137053;5576.944230557642;5680.77276908919;5681.687507186979;5691.373948865705;5695.401982791913;5565;5621.74702138413;5639.041362968387;5661.122526008476;5704.80033198836;5754.395788442321;5755.050416681201;5810.244668193108;5816.680082595498;5821.301443826598;5471.097268950579;5615.073512870494;5617.915731264116;5623.943760562342;5625.521830644441 04:58:22 INFO - PROCESS | 25131 | |1;ext.html;580.3789944759106;595.9255093113361;603.8520562462197;605.3008774865027;617.7961272779983;31857.522137167933;43019.35470967934;43585.99999999996;43597.346039809425;43666.56333436665;43169.35245971313;43542.34686479705;43571.78214108925;43615.127697446034;43637.7818110909;8189.303909167728;8965.865512017308;9116.407433516833;9124.133207345292;10651.627193048245;10529.73675658111;10867.510962006689;10881.727956801104;10884.945575272113;10888.183386246024;10958.56165753369;10995.670129896116;10999.670009899675;11009;11011.779764404708;8287.502749834968;10553.155747540179;10793.460326983619;10947.781044379108;11005.779884402307;10715.678529644123;10798.676039718743;10801.5679372825;10801.729956751065;10806.35161890289;41193.79403102995;41198.794006030075;41199.1760164798;41447.792761036;41480.79259603683;3280.3111346617;3281.2453135778906;3313.2710803623163;3351.6815902489225;3427.7086447651977;32253;32641;32967.6703232967;33144.83427582871;34080.82959585211;4129.401236820644;4191.7484950902735;4207.779743874273;4215.831366745327;4267.509236437819;335168;335649.6435035643;335886;336165;338845;56671.14993275104;56960;57017.42982570204;57070.71464642733;57142.7142864283;36718.8164059178;37326.44010339847;37420.625793742256;39113.413298800544;59008.70495647494;13291.268980205989;13325.13386629866;13331.533396331275;13338.733225335633;13339;10424.009719076676;11123.610673626385;11346.886531134583;11356.716082098013;11529.192956493132;18977;19428.571428571428;19471.221151154077;19737.70393444071;19800.60398792016;6984.428503586754;7028.297170282931;7070.186928503185;7081.645917704094;7086.681099350541;931.4504442379028;1011.4083261292079;1018.6231094495084;1078.3907092492716;1122.119136477861;31345.373092538022;31700.682993169772;31782.523262151088;32259.51610725841;32420.35159296848;1115.7991561519004;1123.6572845282267;1151.7120719820045;1160.2458402038574;1203.5005472728894;16709.248083836257;17103.82896171047;17134.228959696844;17192.828071719123;17387.478375648752;540508;542936;547056;548418;550752;56418;56937.145942810894;57079.42920570824;57086.714566427734;57175.42824571784;1201.7956947318976;1244.3529364730289;1312.3044786263486;1349.406261245049;1364.3860262881544;31111.066667999996;33270.66729332772;33408.498872516444;33567;33604.663953360156;48098.759506202245;48585.5141448581;48616.270755937985;48635.27047094367;48726.75636621936;401261;401897;405426;406288;406659;28383.29041773972;46509.76745116253;46556.53443465522;46731.53268467408;47107;46132.53867461417;46771;47323.76338118287;50977.74511127569;51529;41794.79102604467;43842.780786095864;44018;44050.77974610106;44798;42079.36880946849;42817;43524.129517408845;44091.338629919934;44463.77768111268;42180.15639687128;42777.78611106925;42954.78522607367;43651.345229820945;43786.34320485131;36801.63198367982;38176.427353590276;38366.424503633025;39108.413373798845;39271;28146.155615331572;28532.42935141244;28616.71383286224;28786.856065719538;28887.7111228885 04:58:22 INFO - PROCESS | 25131 | |2;jquery.html;379.7721367179691;394.77892380267053;405.51945944056297;405.76465649923045;420.07583316703244;13634.318284085793;18551.536211594717;19868.801311986877;19908;19982.300619478323;12365.629031129058;12780.744385112292;12958;13045.08684392093;13072.73854522909;4026.778527181004;5391.757370918308;5543.8891222175735;5547.667139971594;5551.916721249185;4168.062186008139;4229.957700423002;4232.38630398592;5654.9151762723595;5844.152597873327;5922.496587790043;5980.6112602680905;5992.0712289595185;5994.820155395323;6000.729967151467;5832.504237139849;5948.940510594882;5961.761529538813;5988;5992.850178745544;4957.182064959277;5173.922391164136;5240.633155679104;5772.740226689809;5788.073908174714;14272.85727142736;14525.636859078502;14606.46632103635;14735.410583576644;14784.334704938303;2125.5961367340155;2174.6194415977297;2323.3959170615594;2584.056819260956;2688.556388195945;10476.842847357295;10565.101966332872;10571.682849514527;14204;14711.190884501348;1863.4595967169505;1872.9719054214197;1890.3289332287115;1917.6356492266425;1935.8451323894196;76199;83809;84034.57982710048;84993.57503212568;85121.14878851194;10882.945585272022;11007.889921100686;11148.665540033811;11274.549018039186;11382.772344553063;16011.59971000711;16038;16044.598885027968;16079.437219697254;16082.597935051483;7683.116441609175;7924.049114106344;8105.513669179868;8107.391945604103;8155.755327340189;7464.925350746532;7465.58939258346;7468.626568671545;7653.732119375795;7698.538087714643;4783.014695041011;4831.516848315211;4879.731614761151;4993.875153121201;5031.320771695848;1920.980790192108;1983.8512111591688;1998.600279944017;2020.6564883969465;2041.57127003328;872.8385248729005;948.8624149498285;985.7190700650281;1016.6340117557673;1060.0035966191756;19886.602267954848;20336.389908302775;20472;20603.278885239244;20798.48003799887;945.479986007693;950.7955789505289;976.7460460280379;1010.6664800615779;1038.3925403638805;10845.728856778644;11180.161487888441;11219.351686591977;11239.887601124048;11369.488373023234;470422;474954;519141;527019;528107;91836;92509.5374523123;92944.53527732319;93819;94017;941.6610020392653;955.3646824861389;978.256525040974;984.468387070988;985.5466485416645;15305.85206109524;15489;17850.464486065437;17909;18184.36354727578;35686.82156589304;40293.1941361177;40615.18769624532;40761.59238407697;40832.591674082876;57247;57661;58554.414455854894;58829.41170588239;59178.70410647919;8275.54484503358;8687.261582765535;8849.336299777413;8916;9090.500022498562;11567.710807229887;13457.730845383234;13475.528356507475;13551.457941682222;13670.726585468035;11168.776624467628;11270.887291126985;11633.88366116328;11683.941580292045;12025.639230822739;11360.772784544097;11470.655880323604;11671.241369310968;11961.700957476132;11990.640280791591;11416.600418985296;11578.71053223676;11878.287302761863;11914.52341906314;12020.879791201976;8735.606897689746;8780.253678437404;8794;9070.229030532479;9193.678221262224;8733.519656418957;11736.237144585575;11884.524619015143;12004.459799308877;12191.75616487683 04:58:22 INFO - PROCESS | 25131 | |3;mootools.html;1323.954076279739;1453.5784622459485;1504.5185540627;1607.3891921069992;1778.8310110539503;7693.153753087163;8165.959170204148;8417.873731894026;8425.494470331785;8656.394052416334;8417.663293468255;8442;8450.154984501547;8463.238308552229;8466.153384661531;2421.794147497465;2447.400386905211;2449.8285120041605;2450.644656524803;2450.718167410753;2395.053953688292;2443.657887895687;2444.5966415541416;2450.142450142445;2454.128784281581;2400.2079313826393;2407.8194135439912;2408.8072954163626;2438.76831700989;2440.3777036855668;2378.38162077861;2406.109739396426;2411.794997425221;2412.2039726890084;2412.49337639096;2359.421941624296;2376.4652953085592;2376.8455050421667;2383.296927406402;2383.4041489627593;8626.784330391727;8681.218690317839;8686.609102590335;8693.087225841322;9072.77318067047;5417.349918009824;5558.416366281523;5564.27664403629;5568.470995255444;5576.832695019156;3429.5884493860895;3479.1476088358513;3506.4389697648253;3545.166885781839;3552.8578856845697;1258.3456602566705;1264.3425418782274;1277.1315505456287;1310.9606711798565;1312.4946895445228;12716.554920577735;12852.036097292741;12880.742385152245;12884.54904078362;12893.806592901114;4450.465944086665;4696.5303469653445;4777.4505931818285;4849.030193961222;4850.175470170079;10035.899641003643;10037.197024238043;10042.598296068076;10058.245631577662;10060.144887684628;5198.636095473356;5223.738813059332;5226.425093239741;5227.085260079471;5234.7906083756225;4307.8276868924895;4730.976345118322;4859.757012149378;4865.002674451729;4941.901161976741;3949.3681011038097;4034.1326614777963;4108.870060733262;4146.1914926589025;4149.937750933738;1248.6254123762835;1260.0486632592333;1264.7786637338613;1274.9745005099846;1283.3006011723633;752.059925093633;768.1243382543904;907.981840363189;920.1442658327819;945.7513441329056;5559.082751345983;5731.799387021434;5928.881422371528;5951.1073338999495;6026.668533230714;919.090100800203;940.5391358234552;946.786972931085;950.9572069256761;967.4147140979746;3801.6198380161763;3823.598522155189;3839.404892241707;3910.2961466936226;3941.8226179821977;11127.554897803997;11535.307881526967;11554.306741595532;11559.190856640123;11597.478113484913;11464.197506174654;11471.942640286912;11472;11519.19365644397;11522.76954460923;8138.918610813935;8472.576371181292;8495.36284778644;8662.30701543875;8662.826743465095;5752.453516915886;5830;5959.7020148991505;5989.490893274121;6170.444659980623;10076.244281678757;10255.897441025494;10302.587896484356;10359.32664376813;10401.27191096616;10318.122959548522;10329.12202462799;10463.68608941733;10646.095081918123;10657.094146997591;7120.1099862517185;7848.332891704269;7980.84038319242;8002.679892804223;8100.311473524816;8763.561821909007;9019.684311049083;9059.864102038342;9069.682561110594;9073.727788166365;7686.57723825195;7796.337311328601;8011.198880112175;8111.553864537507;8220.753377398449;7816.53100813953;7978.760637180894;7999.4400391972;8005.639746211551;8010.519368837889;7372.741954031583;7411.370033547209;7419.480636355619;7484.139323977813;7505.924940750741;5167.560757335668;5221.399539053057;5224.87754193252;5245.291885595396;5279.973600131974;5047.899042019212;5082.720450375265;5110.284560161506;5152.639315248047;5305.416404195458 04:58:22 INFO - PROCESS | 25131 | |4;prototype.html;322.3681584095172;333.61967357212774;334.37805681432536;340.17846899737134;343.0292272867781;5469.890602187958;5480.314960629921;5512.614117011811;5522.5581953443725;5529.474699903508;5109.6678715883445;5218.47815218478;5232.372115346164;5250.188684903712;5417.566594672427;4072.6538244249273;4166.166886569899;4223.064969845958;4230.407742915994;4255.893602659943;4226.746395216282;4234.491860976687;4248.150369926012;4253.064325848324;4261.893452663693;4155.168966206771;4210.536840947493;4229.429027081335;4230.598093181143;4242.618164365206;4186.413902053715;4210.3684447332835;4211.515675697304;4213.620774130343;4217.409562661214;3746.1009357754215;3749.2314075614436;3754.8498060077563;3763.5483741951143;3767.8492860285555;5030.641726733761;5218.478152184789;5234.136367499358;5271.393789714193;5285.022270879861;4836.443808961979;4836.637252206098;4854.757262136879;4866.683665561763;4873.34209881665;5603.831885043455;5623.353314368859;5635.070213414781;5637.238972738669;5639.464250896158;4007.8196481158416;4097.385392191165;4107.835686572564;4131.400946862688;4134.524529679095;5775.711214439261;5814.941850581482;5826.737796799154;5883.79406720767;5914.4676979071655;4199.328107502785;4199.601037901394;4199.790010499462;4202.516710578261;4220.704550681423;4107.507099148122;4275.29457639486;4294.957050429518;4304.268274393361;4308.3968244445805;4774.641901857374;4812.133815913099;4826.155422800995;4841.709497430165;4851.51484851512;5644.5286149018875;5656;5714.942850571441;5718.714064296769;5721.141828725641;1375.5735721926235;1433.2260579287276;1471.749802533572;1487.910725356482;1488.4343949299198;896.1844721303582;915.5330781301451;922.4695799915075;926.36081104038;929.6327950459531;4803.735794531264;4817;4823.493533179036;4831.710097394168;4868.878278043077;5038.067957427887;5055.949440505621;5086.3896332440345;5089.745512724349;5105.923411148836;4588.856633206265;4787.497312782178;4802.6638135330195;4804.927926081112;4804.95195048052;2668.4396276781704;2696.6089916961932;2723.3736240664757;2729.126679462553;2735.3571910600995;5275.709835959059;5281.709505977208;5332.466753324714;5363.570914326844;5372.731363431891;4820.758962051884;5170.508801663836;5223.406860907459;5240.554552863049;5244.875825290764;5192.92210616841;5241.868953276198;5264;5270.314859068297;5281.39263984639;5028.019536190408;5032.446430892525;5056.292119103255;5076;5101.46434624374;4698.976505117453;4970.726610036482;4981.476944920876;5017.4731653175895;5032.238716449214;5217.582483473331;5259.316288882421;5259.921101183409;5267.762950667306;5273.235380869829;3766.3804564037728;3783.5459744830796;3795.411711184771;3799.8100094995693;3825.5218097737784;5584.3857175711455;5614.29821272341;5727.8568035799435;5749.9712501437225;5815.5929084963645;5559.138333558305;5627.606067575394;5635.802746903839;5637.520810731134;5639.351474580476;5499.917501237404;5527.44725527444;5543.916841247304;5609.49514543693;5637.379888212376;5525.944740552543;5536.584756143225;5542.473465020817;5587.245721827501;5600.551955843442;5703.976892247116;5711.828645140652;5714.085746280575;5728.455796699307;5737;5385.9192112119135;5418.315545429146;5439;5486.561075114061;5498.45015498447 04:58:22 INFO - PROCESS | 25131 | |5;yui.html;653.8626888353446;692.0346117166553;798.3174385900056;811.3225456743619;827.4497459189639;15437.305321260543;16365.83634163658;16378.918105409486;16392.672146557063;16411.753823692656;15411.845881541181;15573.922130389332;15585;15588.064716117042;15596;646.9967650161766;758.6775620361352;780.2626517940535;789.0964845252183;795.2127393880046;788.2747871957783;794.8291117409756;800.9199080092003;802.2018091998469;802.7471346525855;844.9493030418164;845.8773477845708;847.1867007672641;847.3687103108193;849;692.5844493304028;824.3116997307255;825.19131251374;841.5203334099566;843.3084870406269;614.4224429036691;614.9477294429979;615.8398816307747;616.0697347006009;616.2574098211644;15163.924180379028;15211.847881521264;15213.31540080699;15231.162286074261;15237;14072.437102515887;14270.500532481417;14547.199904005276;14578.198199099046;14730.263486825615;13360;13547.187168769906;13605.591832245049;13635.931820340935;14337.634290342081;4345.130973805251;4406.8457603984025;4475.753833539154;4506.278995360726;4650.046740418206;85130.14869851284;85648.57175714082;86001.13998859994;86928.56535717344;87713;11526.654200374001;11543.19197656156;11551.884481155248;11629.302241865515;11934;21805.781942180693;21855.562888742137;21892.89053554754;21899.014544345544;21927.780722192572;8585.699500517452;8595.226429621363;8966.68616598416;9208;9219.308551858638;4802.447718512346;4926.4088309403105;4939.925901111486;4949.82675606352;5094.566961808214;3133.27934575049;3223;3234.967650323467;3247.155739507745;3254.6582608825734;596.472122171881;607.4229481992126;608.9086637004397;616.3374372549528;625.2091104752492;627.9937200627935;655.3642966322659;665.3213722003626;680.041141989792;688.4389222783416;9137;9713.805723885484;9782.364146330467;9853.901460985442;10159.339642923187;624.9438449021139;665.9667016649148;676.4250387170865;711.1537270647913;719.3130560314992;4528.5245049270015;4827.806887724451;4875.902481950412;4955.157623204064;5009.649324547319;108364;117966;119027;119380.40309798396;123722.38138809429;84952.57523712341;108535;109961.45019274853;110295.97748551656;113133.43433282945;83929.58035209785;88003;90637;93364.53317733368;93788;9202.815943681222;9355.25157987373;9389.089382291511;9417.576209070472;9444.574994125418;51039.74480127576;51621.48378516167;52111.73944130407;53116.73441632767;54580.454195459126;69242.65378673075;70681;76401;77100.61449692716;80923.59538202272;11496.79283675202;11597.536098555964;11656.18406711556;11703.122265829927;11867.525298987945;13964.860351396355;15236.695266094837;15313.310901009705;15544.689106218038;15589.610259743597;14330;14730.63173420673;14880.7767883484;14880.925595372388;15396.69206615839;14094.295285235492;14157.292135393394;14161.150330980174;14565.781513277521;14774.18741969159;13714.519991800638;13888.444462221398;14375.209363485108;14615.634609134857;14781.852181478478;11050.171237157407;11060;11168.329900206014;11397.145214108808;11442.656720298404;10902.907904781683;11547.422628868691;12454.25274483534;12471.314077725812;12581.93709031449 04:58:22 INFO - PROCESS | 25131 | __end_tp_report 04:58:22 INFO - PROCESS | 25131 | __start_cc_report 04:58:22 INFO - PROCESS | 25131 | _x_x_mozilla_cycle_collect,978 04:58:22 INFO - PROCESS | 25131 | __end_cc_report 04:58:22 INFO - PROCESS | 25131 | __startTimestamp1470139102916__endTimestamp 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | ------- Summary: start ------- 04:58:22 INFO - PROCESS | 25131 | Number of tests: 6 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#0] dojo.html Cycles:165 Average:5410.80 Median:5616.33 stddev:627.73 (11.2%) stddev-sans-first:602.61 04:58:22 INFO - PROCESS | 25131 | Values: 3087.3 3087.9 3101.3 3124.3 3148.4 5307.8 5388.2 5424.8 5445.5 5459.3 5394.6 5445.4 5476.7 5575.0 5586.7 4847.9 4860.0 4890.4 4959.1 4960.3 4997.0 5028.1 5071.3 5072.2 5077.8 4963.7 5015.5 5023.1 5029.6 5038.1 4807.9 4868.3 4887.6 4916.3 4964.6 4848.0 4857.8 4865.0 4875.4 4999.6 5383.6 5418.8 5423.5 5473.1 5484.0 5616.3 5672.1 5794.2 5796.1 5869.2 5858.6 5872.6 5922.9 5923.0 5931.8 5257.3 5490.3 5497.8 5497.9 5498.6 5871.6 5959.4 5959.8 6044.0 6045.9 5705.4 5729.5 5755.7 5757.7 5764.8 5594.6 5828.4 5834.4 5841.2 5850.7 5413.8 5695.3 5754.3 5812.5 5861.8 5790.5 5834.8 5840.3 5842.5 5846.7 4293.0 4443.4 4445.3 4452.5 4492.8 3913.1 3958.7 3977.8 3977.9 4036.0 5552.0 5554.5 5559.3 5569.8 5612.1 5726.3 5889.8 5910.3 5912.3 5944.1 5332.7 5448.0 5451.7 5469.3 5497.8 4954.7 4960.6 4965.7 4968.9 5008.1 5895.0 6036.4 6042.3 6051.5 6055.1 5794.0 5867.5 5869.9 5890.5 5949.6 6120.4 6125.0 6127.1 6127.7 6135.2 5606.7 5727.3 5777.3 5780.9 5786.9 5767.0 5806.7 5808.0 5820.9 5904.1 5691.9 5701.0 5702.7 5702.9 5707.8 5576.9 5680.8 5681.7 5691.4 5695.4 5565.0 5621.7 5639.0 5661.1 5704.8 5754.4 5755.1 5810.2 5816.7 5821.3 5471.1 5615.1 5617.9 5623.9 5625.5 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#1] ext.html Cycles:180 Average:59315.87 Median:38271.43 stddev:115911.69 (302.9%) stddev-sans-first:116152.96 04:58:22 INFO - PROCESS | 25131 | Values: 580.4 595.9 603.9 605.3 617.8 31857.5 43019.4 43586.0 43597.3 43666.6 43169.4 43542.3 43571.8 43615.1 43637.8 8189.3 8965.9 9116.4 9124.1 10651.6 10529.7 10867.5 10881.7 10884.9 10888.2 10958.6 10995.7 10999.7 11009.0 11011.8 8287.5 10553.2 10793.5 10947.8 11005.8 10715.7 10798.7 10801.6 10801.7 10806.4 41193.8 41198.8 41199.2 41447.8 41480.8 3280.3 3281.2 3313.3 3351.7 3427.7 32253.0 32641.0 32967.7 33144.8 34080.8 4129.4 4191.7 4207.8 4215.8 4267.5 335168.0 335649.6 335886.0 336165.0 338845.0 56671.1 56960.0 57017.4 57070.7 57142.7 36718.8 37326.4 37420.6 39113.4 59008.7 13291.3 13325.1 13331.5 13338.7 13339.0 10424.0 11123.6 11346.9 11356.7 11529.2 18977.0 19428.6 19471.2 19737.7 19800.6 6984.4 7028.3 7070.2 7081.6 7086.7 931.5 1011.4 1018.6 1078.4 1122.1 31345.4 31700.7 31782.5 32259.5 32420.4 1115.8 1123.7 1151.7 1160.2 1203.5 16709.2 17103.8 17134.2 17192.8 17387.5 540508.0 542936.0 547056.0 548418.0 550752.0 56418.0 56937.1 57079.4 57086.7 57175.4 1201.8 1244.4 1312.3 1349.4 1364.4 31111.1 33270.7 33408.5 33567.0 33604.7 48098.8 48585.5 48616.3 48635.3 48726.8 401261.0 401897.0 405426.0 406288.0 406659.0 28383.3 46509.8 46556.5 46731.5 47107.0 46132.5 46771.0 47323.8 50977.7 51529.0 41794.8 43842.8 44018.0 44050.8 44798.0 42079.4 42817.0 43524.1 44091.3 44463.8 42180.2 42777.8 42954.8 43651.3 43786.3 36801.6 38176.4 38366.4 39108.4 39271.0 28146.2 28532.4 28616.7 28786.9 28887.7 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#2] jquery.html Cycles:180 Average:29053.05 Median:2210.78 stddev:83228.45 (3764.7%) stddev-sans-first:83434.08 04:58:22 INFO - PROCESS | 25131 | Values: 379.8 394.8 405.5 405.8 420.1 13634.3 18551.5 19868.8 19908.0 19982.3 12365.6 12780.7 12958.0 13045.1 13072.7 4026.8 5391.8 5543.9 5547.7 5551.9 4168.1 4230.0 4232.4 5654.9 5844.2 5922.5 5980.6 5992.1 5994.8 6000.7 5832.5 5948.9 5961.8 5988.0 5992.9 4957.2 5173.9 5240.6 5772.7 5788.1 14272.9 14525.6 14606.5 14735.4 14784.3 2125.6 2174.6 2323.4 2584.1 2688.6 10476.8 10565.1 10571.7 14204.0 14711.2 1863.5 1873.0 1890.3 1917.6 1935.8 76199.0 83809.0 84034.6 84993.6 85121.1 10882.9 11007.9 11148.7 11274.5 11382.8 16011.6 16038.0 16044.6 16079.4 16082.6 7683.1 7924.0 8105.5 8107.4 8155.8 7464.9 7465.6 7468.6 7653.7 7698.5 4783.0 4831.5 4879.7 4993.9 5031.3 1921.0 1983.9 1998.6 2020.7 2041.6 872.8 948.9 985.7 1016.6 1060.0 19886.6 20336.4 20472.0 20603.3 20798.5 945.5 950.8 976.7 1010.7 1038.4 10845.7 11180.2 11219.4 11239.9 11369.5 470422.0 474954.0 519141.0 527019.0 528107.0 91836.0 92509.5 92944.5 93819.0 94017.0 941.7 955.4 978.3 984.5 985.5 15305.9 15489.0 17850.5 17909.0 18184.4 35686.8 40293.2 40615.2 40761.6 40832.6 57247.0 57661.0 58554.4 58829.4 59178.7 8275.5 8687.3 8849.3 8916.0 9090.5 11567.7 13457.7 13475.5 13551.5 13670.7 11168.8 11270.9 11633.9 11683.9 12025.6 11360.8 11470.7 11671.2 11961.7 11990.6 11416.6 11578.7 11878.3 11914.5 12020.9 8735.6 8780.3 8794.0 9070.2 9193.7 8733.5 11736.2 11884.5 12004.5 12191.8 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#3] mootools.html Cycles:180 Average:5903.80 Median:4849.60 stddev:3382.82 (69.8%) stddev-sans-first:3374.79 04:58:22 INFO - PROCESS | 25131 | Values: 1324.0 1453.6 1504.5 1607.4 1778.8 7693.2 8166.0 8417.9 8425.5 8656.4 8417.7 8442.0 8450.2 8463.2 8466.2 2421.8 2447.4 2449.8 2450.6 2450.7 2395.1 2443.7 2444.6 2450.1 2454.1 2400.2 2407.8 2408.8 2438.8 2440.4 2378.4 2406.1 2411.8 2412.2 2412.5 2359.4 2376.5 2376.8 2383.3 2383.4 8626.8 8681.2 8686.6 8693.1 9072.8 5417.3 5558.4 5564.3 5568.5 5576.8 3429.6 3479.1 3506.4 3545.2 3552.9 1258.3 1264.3 1277.1 1311.0 1312.5 12716.6 12852.0 12880.7 12884.5 12893.8 4450.5 4696.5 4777.5 4849.0 4850.2 10035.9 10037.2 10042.6 10058.2 10060.1 5198.6 5223.7 5226.4 5227.1 5234.8 4307.8 4731.0 4859.8 4865.0 4941.9 3949.4 4034.1 4108.9 4146.2 4149.9 1248.6 1260.0 1264.8 1275.0 1283.3 752.1 768.1 908.0 920.1 945.8 5559.1 5731.8 5928.9 5951.1 6026.7 919.1 940.5 946.8 951.0 967.4 3801.6 3823.6 3839.4 3910.3 3941.8 11127.6 11535.3 11554.3 11559.2 11597.5 11464.2 11471.9 11472.0 11519.2 11522.8 8138.9 8472.6 8495.4 8662.3 8662.8 5752.5 5830.0 5959.7 5989.5 6170.4 10076.2 10255.9 10302.6 10359.3 10401.3 10318.1 10329.1 10463.7 10646.1 10657.1 7120.1 7848.3 7980.8 8002.7 8100.3 8763.6 9019.7 9059.9 9069.7 9073.7 7686.6 7796.3 8011.2 8111.6 8220.8 7816.5 7978.8 7999.4 8005.6 8010.5 7372.7 7411.4 7419.5 7484.1 7505.9 5167.6 5221.4 5224.9 5245.3 5280.0 5047.9 5082.7 5110.3 5152.6 5305.4 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#4] prototype.html Cycles:180 Average:4566.94 Median:5056.12 stddev:1312.34 (26.0%) stddev-sans-first:1276.76 04:58:22 INFO - PROCESS | 25131 | Values: 322.4 333.6 334.4 340.2 343.0 5469.9 5480.3 5512.6 5522.6 5529.5 5109.7 5218.5 5232.4 5250.2 5417.6 4072.7 4166.2 4223.1 4230.4 4255.9 4226.7 4234.5 4248.2 4253.1 4261.9 4155.2 4210.5 4229.4 4230.6 4242.6 4186.4 4210.4 4211.5 4213.6 4217.4 3746.1 3749.2 3754.8 3763.5 3767.8 5030.6 5218.5 5234.1 5271.4 5285.0 4836.4 4836.6 4854.8 4866.7 4873.3 5603.8 5623.4 5635.1 5637.2 5639.5 4007.8 4097.4 4107.8 4131.4 4134.5 5775.7 5814.9 5826.7 5883.8 5914.5 4199.3 4199.6 4199.8 4202.5 4220.7 4107.5 4275.3 4295.0 4304.3 4308.4 4774.6 4812.1 4826.2 4841.7 4851.5 5644.5 5656.0 5714.9 5718.7 5721.1 1375.6 1433.2 1471.7 1487.9 1488.4 896.2 915.5 922.5 926.4 929.6 4803.7 4817.0 4823.5 4831.7 4868.9 5038.1 5055.9 5086.4 5089.7 5105.9 4588.9 4787.5 4802.7 4804.9 4805.0 2668.4 2696.6 2723.4 2729.1 2735.4 5275.7 5281.7 5332.5 5363.6 5372.7 4820.8 5170.5 5223.4 5240.6 5244.9 5192.9 5241.9 5264.0 5270.3 5281.4 5028.0 5032.4 5056.3 5076.0 5101.5 4699.0 4970.7 4981.5 5017.5 5032.2 5217.6 5259.3 5259.9 5267.8 5273.2 3766.4 3783.5 3795.4 3799.8 3825.5 5584.4 5614.3 5727.9 5750.0 5815.6 5559.1 5627.6 5635.8 5637.5 5639.4 5499.9 5527.4 5543.9 5609.5 5637.4 5525.9 5536.6 5542.5 5587.2 5600.6 5704.0 5711.8 5714.1 5728.5 5737.0 5385.9 5418.3 5439.0 5486.6 5498.5 04:58:22 INFO - PROCESS | 25131 | 04:58:22 INFO - PROCESS | 25131 | [#5] yui.html Cycles:180 Average:21687.85 Median:4726.25 stddev:31464.31 (665.7%) stddev-sans-first:31512.93 04:58:22 INFO - PROCESS | 25131 | Values: 653.9 692.0 798.3 811.3 827.4 15437.3 16365.8 16378.9 16392.7 16411.8 15411.8 15573.9 15585.0 15588.1 15596.0 647.0 758.7 780.3 789.1 795.2 788.3 794.8 800.9 802.2 802.7 844.9 845.9 847.2 847.4 849.0 692.6 824.3 825.2 841.5 843.3 614.4 614.9 615.8 616.1 616.3 15163.9 15211.8 15213.3 15231.2 15237.0 14072.4 14270.5 14547.2 14578.2 14730.3 13360.0 13547.2 13605.6 13635.9 14337.6 4345.1 4406.8 4475.8 4506.3 4650.0 85130.1 85648.6 86001.1 86928.6 87713.0 11526.7 11543.2 11551.9 11629.3 11934.0 21805.8 21855.6 21892.9 21899.0 21927.8 8585.7 8595.2 8966.7 9208.0 9219.3 4802.4 4926.4 4939.9 4949.8 5094.6 3133.3 3223.0 3235.0 3247.2 3254.7 596.5 607.4 608.9 616.3 625.2 628.0 655.4 665.3 680.0 688.4 9137.0 9713.8 9782.4 9853.9 10159.3 624.9 666.0 676.4 711.2 719.3 4528.5 4827.8 4875.9 4955.2 5009.6 108364.0 117966.0 119027.0 119380.4 123722.4 84952.6 108535.0 109961.5 110296.0 113133.4 83929.6 88003.0 90637.0 93364.5 93788.0 9202.8 9355.3 9389.1 9417.6 9444.6 51039.7 51621.5 52111.7 53116.7 54580.5 69242.7 70681.0 76401.0 77100.6 80923.6 11496.8 11597.5 11656.2 11703.1 11867.5 13964.9 15236.7 15313.3 15544.7 15589.6 14330.0 14730.6 14880.8 14880.9 15396.7 14094.3 14157.3 14161.2 14565.8 14774.2 13714.5 13888.4 14375.2 14615.6 14781.9 11050.2 11060.0 11168.3 11397.1 11442.7 10902.9 11547.4 12454.3 12471.3 12581.9 04:58:22 INFO - PROCESS | 25131 | -------- Summary: end -------- 04:58:22 INFO - PROCESS | 25131 | 04:58:23 INFO - TEST-INFO | 25131: exit 0 04:58:23 INFO - TEST-OK | dromaeo_css | took 1115226ms 04:58:23 INFO - TEST-START | kraken 04:58:23 INFO - operating with platform_type : linux_ 04:58:23 INFO - Initialising browser for kraken test... 04:58:23 INFO - TEST-INFO | started process 25326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbyy76E/profile http://localhost:58504/getInfo.html) 04:58:28 INFO - PROCESS | 25326 | __metrics Screen width/height:1600/1200 04:58:28 INFO - PROCESS | 25326 | colorDepth:24 04:58:28 INFO - PROCESS | 25326 | Browser inner width/height: 1024/697 04:58:28 INFO - PROCESS | 25326 | __metrics 04:58:29 INFO - PROCESS | 25326 | 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" 04:58:29 INFO - PROCESS | 25326 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:58:29 INFO - PROCESS | 25326 | console.error: PushService: 04:58:29 INFO - PROCESS | 25326 | stateChangeProcessEnqueue: Error transitioning state 04:58:29 INFO - PROCESS | 25326 | UnknownError 04:58:30 INFO - TEST-INFO | 25326: exit 0 04:58:30 INFO - Browser initialized. 04:58:30 INFO - Running cycle 1/1 for kraken test... 04:58:30 INFO - TEST-INFO | started process 25406 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbyy76E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:58:41 INFO - PROCESS | 25406 | RSS: Main: 156418048 04:58:41 INFO - PROCESS | 25406 | 04:58:44 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-beat-detection) 04:58:45 INFO - PROCESS | 25406 | RSS: Main: 212602880 04:58:45 INFO - PROCESS | 25406 | 04:58:47 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-dft) 04:58:48 INFO - PROCESS | 25406 | RSS: Main: 191598592 04:58:48 INFO - PROCESS | 25406 | 04:58:51 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-fft) 04:58:52 INFO - PROCESS | 25406 | RSS: Main: 269103104 04:58:52 INFO - PROCESS | 25406 | 04:58:54 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58504/tests/kraken/driver.html?testName=audio-oscillator) 04:58:54 INFO - PROCESS | 25406 | RSS: Main: 179400704 04:58:54 INFO - PROCESS | 25406 | 04:58:57 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:58:57 INFO - PROCESS | 25406 | RSS: Main: 184123392 04:58:57 INFO - PROCESS | 25406 | 04:59:00 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-darkroom) 04:59:01 INFO - PROCESS | 25406 | RSS: Main: 219267072 04:59:01 INFO - PROCESS | 25406 | 04:59:04 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58504/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:05 INFO - PROCESS | 25406 | RSS: Main: 220033024 04:59:05 INFO - PROCESS | 25406 | 04:59:08 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58504/tests/kraken/driver.html?testName=json-parse-financial) 04:59:08 INFO - PROCESS | 25406 | RSS: Main: 219922432 04:59:08 INFO - PROCESS | 25406 | 04:59:10 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58504/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:59:10 INFO - PROCESS | 25406 | RSS: Main: 184127488 04:59:10 INFO - PROCESS | 25406 | 04:59:12 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:59:13 INFO - PROCESS | 25406 | RSS: Main: 189296640 04:59:13 INFO - PROCESS | 25406 | 04:59:15 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:59:15 INFO - PROCESS | 25406 | RSS: Main: 192978944 04:59:15 INFO - PROCESS | 25406 | 04:59:18 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:59:18 INFO - PROCESS | 25406 | RSS: Main: 186777600 04:59:18 INFO - PROCESS | 25406 | 04:59:21 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:59:22 INFO - PROCESS | 25406 | RSS: Main: 192872448 04:59:22 INFO - PROCESS | 25406 | 04:59:23 INFO - PROCESS | 25406 | Cycle 1(1): loaded http://localhost:58504/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58504/tests/kraken/driver.html?testName=ai-astar) 04:59:24 INFO - PROCESS | 25406 | RSS: Main: 244191232 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | __start_tp_report 04:59:24 INFO - PROCESS | 25406 | _x_x_mozilla_page_load 04:59:24 INFO - PROCESS | 25406 | _x_x_mozilla_page_load_details 04:59:24 INFO - PROCESS | 25406 | |i|pagename|runs| 04:59:24 INFO - PROCESS | 25406 | |0;ai-astar;101;110;110;110;111;110;112;110;111;111 04:59:24 INFO - PROCESS | 25406 | |1;audio-beat-detection;140;118;120;190;119;120;141;128;158;119 04:59:24 INFO - PROCESS | 25406 | |2;audio-dft;162;160;159;160;160;160;163;165;163;161 04:59:24 INFO - PROCESS | 25406 | |3;audio-fft;75;75;77;114;76;76;75;76;115;74 04:59:24 INFO - PROCESS | 25406 | |4;audio-oscillator;84;83;111;84;84;83;119;83;83;131 04:59:24 INFO - PROCESS | 25406 | |5;imaging-gaussian-blur;117;117;118;116;117;117;116;117;117;117 04:59:24 INFO - PROCESS | 25406 | |6;imaging-darkroom;146;146;147;146;145;146;146;145;145;147 04:59:24 INFO - PROCESS | 25406 | |7;imaging-desaturate;95;96;95;95;95;95;94;94;95;95 04:59:24 INFO - PROCESS | 25406 | |8;json-parse-financial;52;53;52;52;52;52;52;51;50;50 04:59:24 INFO - PROCESS | 25406 | |9;json-stringify-tinderbox;62;63;61;61;63;62;62;62;63;62 04:59:24 INFO - PROCESS | 25406 | |10;stanford-crypto-aes;76;76;84;76;78;76;75;75;75;80 04:59:24 INFO - PROCESS | 25406 | |11;stanford-crypto-ccm;137;122;119;129;124;128;128;130;174;122 04:59:24 INFO - PROCESS | 25406 | |12;stanford-crypto-pbkdf2;156;158;155;156;157;160;158;155;154;155 04:59:24 INFO - PROCESS | 25406 | |13;stanford-crypto-sha256-iterative;64;62;63;62;62;66;62;63;60;62 04:59:24 INFO - PROCESS | 25406 | __end_tp_report 04:59:24 INFO - PROCESS | 25406 | __start_cc_report 04:59:24 INFO - PROCESS | 25406 | _x_x_mozilla_cycle_collect,1690 04:59:24 INFO - PROCESS | 25406 | __end_cc_report 04:59:24 INFO - PROCESS | 25406 | __startTimestamp1470139164148__endTimestamp 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | ------- Summary: start ------- 04:59:24 INFO - PROCESS | 25406 | Number of tests: 14 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.50 stddev:3.10 (2.8%) stddev-sans-first:0.73 04:59:24 INFO - PROCESS | 25406 | Values: 101.0 110.0 110.0 110.0 111.0 110.0 112.0 110.0 111.0 111.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:134.00 stddev:23.37 (17.4%) stddev-sans-first:24.72 04:59:24 INFO - PROCESS | 25406 | Values: 140.0 118.0 120.0 190.0 119.0 120.0 141.0 128.0 158.0 119.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.89 (1.2%) stddev-sans-first:1.99 04:59:24 INFO - PROCESS | 25406 | Values: 162.0 160.0 159.0 160.0 160.0 160.0 163.0 165.0 163.0 161.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#3] audio-fft Cycles:10 Average:83.30 Median:75.50 stddev:16.47 (21.8%) stddev-sans-first:17.19 04:59:24 INFO - PROCESS | 25406 | Values: 75.0 75.0 77.0 114.0 76.0 76.0 75.0 76.0 115.0 74.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:83.00 stddev:18.45 (22.2%) stddev-sans-first:19.18 04:59:24 INFO - PROCESS | 25406 | Values: 84.0 83.0 111.0 84.0 84.0 83.0 119.0 83.0 83.0 131.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 04:59:24 INFO - PROCESS | 25406 | Values: 117.0 117.0 118.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 04:59:24 INFO - PROCESS | 25406 | Values: 146.0 146.0 147.0 146.0 145.0 146.0 146.0 145.0 145.0 147.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:59:24 INFO - PROCESS | 25406 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 95.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#8] json-parse-financial Cycles:10 Average:51.60 Median:52.00 stddev:0.97 (1.9%) stddev-sans-first:1.01 04:59:24 INFO - PROCESS | 25406 | Values: 52.0 53.0 52.0 52.0 52.0 52.0 52.0 51.0 50.0 50.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 04:59:24 INFO - PROCESS | 25406 | Values: 62.0 63.0 61.0 61.0 63.0 62.0 62.0 62.0 63.0 62.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:76.00 stddev:2.88 (3.8%) stddev-sans-first:3.03 04:59:24 INFO - PROCESS | 25406 | Values: 76.0 76.0 84.0 76.0 78.0 76.0 75.0 75.0 75.0 80.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#11] stanford-crypto-ccm Cycles:10 Average:131.30 Median:128.50 stddev:15.85 (12.3%) stddev-sans-first:16.68 04:59:24 INFO - PROCESS | 25406 | Values: 137.0 122.0 119.0 129.0 124.0 128.0 128.0 130.0 174.0 122.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.40 Median:156.50 stddev:1.84 (1.2%) stddev-sans-first:1.94 04:59:24 INFO - PROCESS | 25406 | Values: 156.0 158.0 155.0 156.0 157.0 160.0 158.0 155.0 154.0 155.0 04:59:24 INFO - PROCESS | 25406 | 04:59:24 INFO - PROCESS | 25406 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:1.58 (2.5%) stddev-sans-first:1.59 04:59:24 INFO - PROCESS | 25406 | Values: 64.0 62.0 63.0 62.0 62.0 66.0 62.0 63.0 60.0 62.0 04:59:24 INFO - PROCESS | 25406 | -------- Summary: end -------- 04:59:24 INFO - PROCESS | 25406 | 04:59:25 INFO - TEST-INFO | 25406: exit 0 04:59:25 INFO - TEST-OK | kraken | took 61449ms 04:59:25 INFO - SUITE-END | took 1176s 04:59:25 INFO - Completed test suite (00:19:36) 04:59:25 INFO - javascript benchmark 04:59:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3087.3362227121165, 3087.8764849406025, 3101.348716769479, 3124.343887783565, 3148.4018036573048, 5307.840764777063, 5388.191771234312, 5424.755885985141, 5445.537129344, 5459.344878614567, 5394.622376433651, 5445.428230035848, 5476.67139971601, 5575.0, 5586.720663966806, 4847.927281090787, 4860.027994401125, 4890.437599676029, 4959.057779021974, 4960.305557221992, 4996.975015124919, 5028.120078986181, 5071.290019397287, 5072.163093089635, 5077.847664570068, 4963.70217786934, 5015.523525265094, 5023.0707319145895, 5029.597632189434, 5038.093143234217, 4807.903841923142, 4868.269759536098, 4887.633427492952, 4916.311716359677, 4964.627652926045, 4848.0, 4857.781399837016, 4865.026994601094, 4875.366202393701, 4999.575036121934, 5383.623146379755, 5418.810341638024, 5423.511883930465, 5473.124300111963, 5484.0, 5616.32604087508, 5672.092465205547, 5794.217780599607, 5796.101604251347, 5869.2369991901205, 5858.56060795442, 5872.559558033122, 5922.8815423691285, 5922.96348139074, 5931.822045338647, 5257.263983042377, 5490.313710786152, 5497.780088796403, 5497.917531236994, 5498.615096943216, 5871.647701137946, 5959.374265702126, 5959.761609535657, 6044.0, 6045.879082418327, 5705.429457054344, 5729.541636669056, 5755.6834374108985, 5757.683327417032, 5764.798232061858, 5594.636348637326, 5828.446297601721, 5834.445727655866, 5841.211436456109, 5850.736716847752, 5413.7834486620095, 5695.288088988877, 5754.309482862083, 5812.476877081083, 5861.824145275648, 5790.507806836466, 5834.824955251349, 5840.2991641003355, 5842.503387212135, 5846.736896839652, 4292.978535107304, 4443.400140980991, 4445.310976798602, 4452.487963884195, 4492.842750503782, 3913.0608653922855, 3958.722889397772, 3977.8209980550946, 3977.940330895039, 4035.9798201008807, 5552.0, 5554.527865131509, 5559.27729395176, 5569.777208911679, 5612.102063669793, 5726.341470730836, 5889.764409423575, 5910.261217347832, 5912.260967379078, 5944.138099975479, 5332.706701131475, 5447.972760136174, 5451.727413629303, 5469.2616496772425, 5497.835064948058, 4954.677945933503, 4960.578350840219, 4965.677230979974, 4968.850934471971, 5008.12357837377, 5895.0, 6036.426539478743, 6042.305134909454, 6051.485623721945, 6055.1220073090035, 5794.0, 5867.471927526543, 5869.853253668522, 5890.4993075587345, 5949.553783466344, 6120.44915957566, 6124.969375153096, 6127.050307202391, 6127.662978536223, 6135.233095863017, 5606.691631960118, 5727.31272247333, 5777.3356064051495, 5780.855478613068, 5786.855328616818, 5766.971165144148, 5806.65160090396, 5807.970960145341, 5820.883582328414, 5904.084866845646, 5691.8861622768145, 5700.971495142498, 5702.714864256687, 5702.914456283076, 5707.828765137053, 5576.944230557642, 5680.77276908919, 5681.687507186979, 5691.373948865705, 5695.401982791913, 5565.0, 5621.74702138413, 5639.041362968387, 5661.122526008476, 5704.80033198836, 5754.395788442321, 5755.050416681201, 5810.244668193108, 5816.680082595498, 5821.301443826598, 5471.097268950579, 5615.073512870494, 5617.915731264116, 5623.943760562342, 5625.521830644441], "value": 5367.462895964945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.3789944759106, 595.9255093113361, 603.8520562462197, 605.3008774865027, 617.7961272779983, 31857.522137167933, 43019.35470967934, 43585.99999999996, 43597.346039809425, 43666.56333436665, 43169.35245971313, 43542.34686479705, 43571.78214108925, 43615.127697446034, 43637.7818110909, 8189.303909167728, 8965.865512017308, 9116.407433516833, 9124.133207345292, 10651.627193048245, 10529.73675658111, 10867.510962006689, 10881.727956801104, 10884.945575272113, 10888.183386246024, 10958.56165753369, 10995.670129896116, 10999.670009899675, 11009.0, 11011.779764404708, 8287.502749834968, 10553.155747540179, 10793.460326983619, 10947.781044379108, 11005.779884402307, 10715.678529644123, 10798.676039718743, 10801.5679372825, 10801.729956751065, 10806.35161890289, 41193.79403102995, 41198.794006030075, 41199.1760164798, 41447.792761036, 41480.79259603683, 3280.3111346617, 3281.2453135778906, 3313.2710803623163, 3351.6815902489225, 3427.7086447651977, 32253.0, 32641.0, 32967.6703232967, 33144.83427582871, 34080.82959585211, 4129.401236820644, 4191.7484950902735, 4207.779743874273, 4215.831366745327, 4267.509236437819, 335168.0, 335649.6435035643, 335886.0, 336165.0, 338845.0, 56671.14993275104, 56960.0, 57017.42982570204, 57070.71464642733, 57142.7142864283, 36718.8164059178, 37326.44010339847, 37420.625793742256, 39113.413298800544, 59008.70495647494, 13291.268980205989, 13325.13386629866, 13331.533396331275, 13338.733225335633, 13339.0, 10424.009719076676, 11123.610673626385, 11346.886531134583, 11356.716082098013, 11529.192956493132, 18977.0, 19428.571428571428, 19471.221151154077, 19737.70393444071, 19800.60398792016, 6984.428503586754, 7028.297170282931, 7070.186928503185, 7081.645917704094, 7086.681099350541, 931.4504442379028, 1011.4083261292079, 1018.6231094495084, 1078.3907092492716, 1122.119136477861, 31345.373092538022, 31700.682993169772, 31782.523262151088, 32259.51610725841, 32420.35159296848, 1115.7991561519004, 1123.6572845282267, 1151.7120719820045, 1160.2458402038574, 1203.5005472728894, 16709.248083836257, 17103.82896171047, 17134.228959696844, 17192.828071719123, 17387.478375648752, 540508.0, 542936.0, 547056.0, 548418.0, 550752.0, 56418.0, 56937.145942810894, 57079.42920570824, 57086.714566427734, 57175.42824571784, 1201.7956947318976, 1244.3529364730289, 1312.3044786263486, 1349.406261245049, 1364.3860262881544, 31111.066667999996, 33270.66729332772, 33408.498872516444, 33567.0, 33604.663953360156, 48098.759506202245, 48585.5141448581, 48616.270755937985, 48635.27047094367, 48726.75636621936, 401261.0, 401897.0, 405426.0, 406288.0, 406659.0, 28383.29041773972, 46509.76745116253, 46556.53443465522, 46731.53268467408, 47107.0, 46132.53867461417, 46771.0, 47323.76338118287, 50977.74511127569, 51529.0, 41794.79102604467, 43842.780786095864, 44018.0, 44050.77974610106, 44798.0, 42079.36880946849, 42817.0, 43524.129517408845, 44091.338629919934, 44463.77768111268, 42180.15639687128, 42777.78611106925, 42954.78522607367, 43651.345229820945, 43786.34320485131, 36801.63198367982, 38176.427353590276, 38366.424503633025, 39108.413373798845, 39271.0, 28146.155615331572, 28532.42935141244, 28616.71383286224, 28786.856065719538, 28887.7111228885], "value": 20358.304459072442, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.7721367179691, 394.77892380267053, 405.51945944056297, 405.76465649923045, 420.07583316703244, 13634.318284085793, 18551.536211594717, 19868.801311986877, 19908.0, 19982.300619478323, 12365.629031129058, 12780.744385112292, 12958.0, 13045.08684392093, 13072.73854522909, 4026.778527181004, 5391.757370918308, 5543.8891222175735, 5547.667139971594, 5551.916721249185, 4168.062186008139, 4229.957700423002, 4232.38630398592, 5654.9151762723595, 5844.152597873327, 5922.496587790043, 5980.6112602680905, 5992.0712289595185, 5994.820155395323, 6000.729967151467, 5832.504237139849, 5948.940510594882, 5961.761529538813, 5988.0, 5992.850178745544, 4957.182064959277, 5173.922391164136, 5240.633155679104, 5772.740226689809, 5788.073908174714, 14272.85727142736, 14525.636859078502, 14606.46632103635, 14735.410583576644, 14784.334704938303, 2125.5961367340155, 2174.6194415977297, 2323.3959170615594, 2584.056819260956, 2688.556388195945, 10476.842847357295, 10565.101966332872, 10571.682849514527, 14204.0, 14711.190884501348, 1863.4595967169505, 1872.9719054214197, 1890.3289332287115, 1917.6356492266425, 1935.8451323894196, 76199.0, 83809.0, 84034.57982710048, 84993.57503212568, 85121.14878851194, 10882.945585272022, 11007.889921100686, 11148.665540033811, 11274.549018039186, 11382.772344553063, 16011.59971000711, 16038.0, 16044.598885027968, 16079.437219697254, 16082.597935051483, 7683.116441609175, 7924.049114106344, 8105.513669179868, 8107.391945604103, 8155.755327340189, 7464.925350746532, 7465.58939258346, 7468.626568671545, 7653.732119375795, 7698.538087714643, 4783.014695041011, 4831.516848315211, 4879.731614761151, 4993.875153121201, 5031.320771695848, 1920.980790192108, 1983.8512111591688, 1998.600279944017, 2020.6564883969465, 2041.57127003328, 872.8385248729005, 948.8624149498285, 985.7190700650281, 1016.6340117557673, 1060.0035966191756, 19886.602267954848, 20336.389908302775, 20472.0, 20603.278885239244, 20798.48003799887, 945.479986007693, 950.7955789505289, 976.7460460280379, 1010.6664800615779, 1038.3925403638805, 10845.728856778644, 11180.161487888441, 11219.351686591977, 11239.887601124048, 11369.488373023234, 470422.0, 474954.0, 519141.0, 527019.0, 528107.0, 91836.0, 92509.5374523123, 92944.53527732319, 93819.0, 94017.0, 941.6610020392653, 955.3646824861389, 978.256525040974, 984.468387070988, 985.5466485416645, 15305.85206109524, 15489.0, 17850.464486065437, 17909.0, 18184.36354727578, 35686.82156589304, 40293.1941361177, 40615.18769624532, 40761.59238407697, 40832.591674082876, 57247.0, 57661.0, 58554.414455854894, 58829.41170588239, 59178.70410647919, 8275.54484503358, 8687.261582765535, 8849.336299777413, 8916.0, 9090.500022498562, 11567.710807229887, 13457.730845383234, 13475.528356507475, 13551.457941682222, 13670.726585468035, 11168.776624467628, 11270.887291126985, 11633.88366116328, 11683.941580292045, 12025.639230822739, 11360.772784544097, 11470.655880323604, 11671.241369310968, 11961.700957476132, 11990.640280791591, 11416.600418985296, 11578.71053223676, 11878.287302761863, 11914.52341906314, 12020.879791201976, 8735.606897689746, 8780.253678437404, 8794.0, 9070.229030532479, 9193.678221262224, 8733.519656418957, 11736.237144585575, 11884.524619015143, 12004.459799308877, 12191.75616487683], "value": 9005.228273716772, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.954076279739, 1453.5784622459485, 1504.5185540627, 1607.3891921069992, 1778.8310110539503, 7693.153753087163, 8165.959170204148, 8417.873731894026, 8425.494470331785, 8656.394052416334, 8417.663293468255, 8442.0, 8450.154984501547, 8463.238308552229, 8466.153384661531, 2421.794147497465, 2447.400386905211, 2449.8285120041605, 2450.644656524803, 2450.718167410753, 2395.053953688292, 2443.657887895687, 2444.5966415541416, 2450.142450142445, 2454.128784281581, 2400.2079313826393, 2407.8194135439912, 2408.8072954163626, 2438.76831700989, 2440.3777036855668, 2378.38162077861, 2406.109739396426, 2411.794997425221, 2412.2039726890084, 2412.49337639096, 2359.421941624296, 2376.4652953085592, 2376.8455050421667, 2383.296927406402, 2383.4041489627593, 8626.784330391727, 8681.218690317839, 8686.609102590335, 8693.087225841322, 9072.77318067047, 5417.349918009824, 5558.416366281523, 5564.27664403629, 5568.470995255444, 5576.832695019156, 3429.5884493860895, 3479.1476088358513, 3506.4389697648253, 3545.166885781839, 3552.8578856845697, 1258.3456602566705, 1264.3425418782274, 1277.1315505456287, 1310.9606711798565, 1312.4946895445228, 12716.554920577735, 12852.036097292741, 12880.742385152245, 12884.54904078362, 12893.806592901114, 4450.465944086665, 4696.5303469653445, 4777.4505931818285, 4849.030193961222, 4850.175470170079, 10035.899641003643, 10037.197024238043, 10042.598296068076, 10058.245631577662, 10060.144887684628, 5198.636095473356, 5223.738813059332, 5226.425093239741, 5227.085260079471, 5234.7906083756225, 4307.8276868924895, 4730.976345118322, 4859.757012149378, 4865.002674451729, 4941.901161976741, 3949.3681011038097, 4034.1326614777963, 4108.870060733262, 4146.1914926589025, 4149.937750933738, 1248.6254123762835, 1260.0486632592333, 1264.7786637338613, 1274.9745005099846, 1283.3006011723633, 752.059925093633, 768.1243382543904, 907.981840363189, 920.1442658327819, 945.7513441329056, 5559.082751345983, 5731.799387021434, 5928.881422371528, 5951.1073338999495, 6026.668533230714, 919.090100800203, 940.5391358234552, 946.786972931085, 950.9572069256761, 967.4147140979746, 3801.6198380161763, 3823.598522155189, 3839.404892241707, 3910.2961466936226, 3941.8226179821977, 11127.554897803997, 11535.307881526967, 11554.306741595532, 11559.190856640123, 11597.478113484913, 11464.197506174654, 11471.942640286912, 11472.0, 11519.19365644397, 11522.76954460923, 8138.918610813935, 8472.576371181292, 8495.36284778644, 8662.30701543875, 8662.826743465095, 5752.453516915886, 5830.0, 5959.7020148991505, 5989.490893274121, 6170.444659980623, 10076.244281678757, 10255.897441025494, 10302.587896484356, 10359.32664376813, 10401.27191096616, 10318.122959548522, 10329.12202462799, 10463.68608941733, 10646.095081918123, 10657.094146997591, 7120.1099862517185, 7848.332891704269, 7980.84038319242, 8002.679892804223, 8100.311473524816, 8763.561821909007, 9019.684311049083, 9059.864102038342, 9069.682561110594, 9073.727788166365, 7686.57723825195, 7796.337311328601, 8011.198880112175, 8111.553864537507, 8220.753377398449, 7816.53100813953, 7978.760637180894, 7999.4400391972, 8005.639746211551, 8010.519368837889, 7372.741954031583, 7411.370033547209, 7419.480636355619, 7484.139323977813, 7505.924940750741, 5167.560757335668, 5221.399539053057, 5224.87754193252, 5245.291885595396, 5279.973600131974, 5047.899042019212, 5082.720450375265, 5110.284560161506, 5152.639315248047, 5305.416404195458], "value": 4714.815319618428, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.3681584095172, 333.61967357212774, 334.37805681432536, 340.17846899737134, 343.0292272867781, 5469.890602187958, 5480.314960629921, 5512.614117011811, 5522.5581953443725, 5529.474699903508, 5109.6678715883445, 5218.47815218478, 5232.372115346164, 5250.188684903712, 5417.566594672427, 4072.6538244249273, 4166.166886569899, 4223.064969845958, 4230.407742915994, 4255.893602659943, 4226.746395216282, 4234.491860976687, 4248.150369926012, 4253.064325848324, 4261.893452663693, 4155.168966206771, 4210.536840947493, 4229.429027081335, 4230.598093181143, 4242.618164365206, 4186.413902053715, 4210.3684447332835, 4211.515675697304, 4213.620774130343, 4217.409562661214, 3746.1009357754215, 3749.2314075614436, 3754.8498060077563, 3763.5483741951143, 3767.8492860285555, 5030.641726733761, 5218.478152184789, 5234.136367499358, 5271.393789714193, 5285.022270879861, 4836.443808961979, 4836.637252206098, 4854.757262136879, 4866.683665561763, 4873.34209881665, 5603.831885043455, 5623.353314368859, 5635.070213414781, 5637.238972738669, 5639.464250896158, 4007.8196481158416, 4097.385392191165, 4107.835686572564, 4131.400946862688, 4134.524529679095, 5775.711214439261, 5814.941850581482, 5826.737796799154, 5883.79406720767, 5914.4676979071655, 4199.328107502785, 4199.601037901394, 4199.790010499462, 4202.516710578261, 4220.704550681423, 4107.507099148122, 4275.29457639486, 4294.957050429518, 4304.268274393361, 4308.3968244445805, 4774.641901857374, 4812.133815913099, 4826.155422800995, 4841.709497430165, 4851.51484851512, 5644.5286149018875, 5656.0, 5714.942850571441, 5718.714064296769, 5721.141828725641, 1375.5735721926235, 1433.2260579287276, 1471.749802533572, 1487.910725356482, 1488.4343949299198, 896.1844721303582, 915.5330781301451, 922.4695799915075, 926.36081104038, 929.6327950459531, 4803.735794531264, 4817.0, 4823.493533179036, 4831.710097394168, 4868.878278043077, 5038.067957427887, 5055.949440505621, 5086.3896332440345, 5089.745512724349, 5105.923411148836, 4588.856633206265, 4787.497312782178, 4802.6638135330195, 4804.927926081112, 4804.95195048052, 2668.4396276781704, 2696.6089916961932, 2723.3736240664757, 2729.126679462553, 2735.3571910600995, 5275.709835959059, 5281.709505977208, 5332.466753324714, 5363.570914326844, 5372.731363431891, 4820.758962051884, 5170.508801663836, 5223.406860907459, 5240.554552863049, 5244.875825290764, 5192.92210616841, 5241.868953276198, 5264.0, 5270.314859068297, 5281.39263984639, 5028.019536190408, 5032.446430892525, 5056.292119103255, 5076.0, 5101.46434624374, 4698.976505117453, 4970.726610036482, 4981.476944920876, 5017.4731653175895, 5032.238716449214, 5217.582483473331, 5259.316288882421, 5259.921101183409, 5267.762950667306, 5273.235380869829, 3766.3804564037728, 3783.5459744830796, 3795.411711184771, 3799.8100094995693, 3825.5218097737784, 5584.3857175711455, 5614.29821272341, 5727.8568035799435, 5749.9712501437225, 5815.5929084963645, 5559.138333558305, 5627.606067575394, 5635.802746903839, 5637.520810731134, 5639.351474580476, 5499.917501237404, 5527.44725527444, 5543.916841247304, 5609.49514543693, 5637.379888212376, 5525.944740552543, 5536.584756143225, 5542.473465020817, 5587.245721827501, 5600.551955843442, 5703.976892247116, 5711.828645140652, 5714.085746280575, 5728.455796699307, 5737.0, 5385.9192112119135, 5418.315545429146, 5439.0, 5486.561075114061, 5498.45015498447], "value": 4150.551581883159, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.8626888353446, 692.0346117166553, 798.3174385900056, 811.3225456743619, 827.4497459189639, 15437.305321260543, 16365.83634163658, 16378.918105409486, 16392.672146557063, 16411.753823692656, 15411.845881541181, 15573.922130389332, 15585.0, 15588.064716117042, 15596.0, 646.9967650161766, 758.6775620361352, 780.2626517940535, 789.0964845252183, 795.2127393880046, 788.2747871957783, 794.8291117409756, 800.9199080092003, 802.2018091998469, 802.7471346525855, 844.9493030418164, 845.8773477845708, 847.1867007672641, 847.3687103108193, 849.0, 692.5844493304028, 824.3116997307255, 825.19131251374, 841.5203334099566, 843.3084870406269, 614.4224429036691, 614.9477294429979, 615.8398816307747, 616.0697347006009, 616.2574098211644, 15163.924180379028, 15211.847881521264, 15213.31540080699, 15231.162286074261, 15237.0, 14072.437102515887, 14270.500532481417, 14547.199904005276, 14578.198199099046, 14730.263486825615, 13360.0, 13547.187168769906, 13605.591832245049, 13635.931820340935, 14337.634290342081, 4345.130973805251, 4406.8457603984025, 4475.753833539154, 4506.278995360726, 4650.046740418206, 85130.14869851284, 85648.57175714082, 86001.13998859994, 86928.56535717344, 87713.0, 11526.654200374001, 11543.19197656156, 11551.884481155248, 11629.302241865515, 11934.0, 21805.781942180693, 21855.562888742137, 21892.89053554754, 21899.014544345544, 21927.780722192572, 8585.699500517452, 8595.226429621363, 8966.68616598416, 9208.0, 9219.308551858638, 4802.447718512346, 4926.4088309403105, 4939.925901111486, 4949.82675606352, 5094.566961808214, 3133.27934575049, 3223.0, 3234.967650323467, 3247.155739507745, 3254.6582608825734, 596.472122171881, 607.4229481992126, 608.9086637004397, 616.3374372549528, 625.2091104752492, 627.9937200627935, 655.3642966322659, 665.3213722003626, 680.041141989792, 688.4389222783416, 9137.0, 9713.805723885484, 9782.364146330467, 9853.901460985442, 10159.339642923187, 624.9438449021139, 665.9667016649148, 676.4250387170865, 711.1537270647913, 719.3130560314992, 4528.5245049270015, 4827.806887724451, 4875.902481950412, 4955.157623204064, 5009.649324547319, 108364.0, 117966.0, 119027.0, 119380.40309798396, 123722.38138809429, 84952.57523712341, 108535.0, 109961.45019274853, 110295.97748551656, 113133.43433282945, 83929.58035209785, 88003.0, 90637.0, 93364.53317733368, 93788.0, 9202.815943681222, 9355.25157987373, 9389.089382291511, 9417.576209070472, 9444.574994125418, 51039.74480127576, 51621.48378516167, 52111.73944130407, 53116.73441632767, 54580.454195459126, 69242.65378673075, 70681.0, 76401.0, 77100.61449692716, 80923.59538202272, 11496.79283675202, 11597.536098555964, 11656.18406711556, 11703.122265829927, 11867.525298987945, 13964.860351396355, 15236.695266094837, 15313.310901009705, 15544.689106218038, 15589.610259743597, 14330.0, 14730.63173420673, 14880.7767883484, 14880.925595372388, 15396.69206615839, 14094.295285235492, 14157.292135393394, 14161.150330980174, 14565.781513277521, 14774.18741969159, 13714.519991800638, 13888.444462221398, 14375.209363485108, 14615.634609134857, 14781.852181478478, 11050.171237157407, 11060.0, 11168.329900206014, 11397.145214108808, 11442.656720298404, 10902.907904781683, 11547.422628868691, 12454.25274483534, 12471.314077725812, 12581.93709031449], "value": 7690.54253800221, "unit": "score"}], "name": "dromaeo_css", "value": 7273.828387233747, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 110.0, 110.0, 111.0, 110.0, 112.0, 110.0, 111.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 118.0, 120.0, 190.0, 119.0, 120.0, 141.0, 128.0, 158.0, 119.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 159.0, 160.0, 160.0, 160.0, 163.0, 165.0, 163.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 114.0, 76.0, 76.0, 75.0, 76.0, 115.0, 74.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 111.0, 84.0, 84.0, 83.0, 119.0, 83.0, 83.0, 131.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 51.0, 50.0, 50.0], "value": 51.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 61.0, 63.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 84.0, 76.0, 78.0, 76.0, 75.0, 75.0, 75.0, 80.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 122.0, 119.0, 129.0, 124.0, 128.0, 128.0, 130.0, 174.0, 122.0], "value": 131.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 158.0, 155.0, 156.0, 157.0, 160.0, 158.0, 155.0, 154.0, 155.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 62.0, 62.0, 66.0, 62.0, 63.0, 60.0, 62.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1482.7999999999997, "alertThreshold": 2.0}]} 04:59:25 INFO - Return code: 0 04:59:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:59:25 INFO - # TBPL SUCCESS # 04:59:25 INFO - Running post-action listener: _resource_record_post_action 04:59:25 INFO - [mozharness: 2016-08-02 11:59:25.476200Z] Finished run-tests step (success) 04:59:25 INFO - Running post-run listener: _resource_record_post_run 04:59:25 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 16384; Write bytes: 543301632; Read time: 436; Write time: 557192 04:59:25 INFO - TinderboxPrint: CPU usage
13.1% 04:59:25 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 436 04:59:25 INFO - TinderboxPrint: I/O write bytes / time
543,301,632 / 557,192 04:59:25 INFO - TinderboxPrint: CPU idle
8,278.9 (86.9%) 04:59:25 INFO - TinderboxPrint: CPU user
1,202.0 (12.6%) 04:59:25 INFO - TinderboxPrint: Swap in / out
0 / 0 04:59:25 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 251543552; Read time: 220; Write time: 331040 04:59:25 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291229696; Read time: 204; Write time: 226052 04:59:26 INFO - Running post-run listener: _upload_blobber_files 04:59:26 INFO - Blob upload gear active. 04:59:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:59:26 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 04:59:26 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'] 04:59:26 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 04:59:26 INFO - (blobuploader) - INFO - Open directory for files ... 04:59:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:59:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:59:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:27 INFO - (blobuploader) - INFO - Done attempting. 04:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:59:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:30 INFO - (blobuploader) - INFO - Done attempting. 04:59:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:59:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:59:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:31 INFO - (blobuploader) - INFO - Done attempting. 04:59:31 INFO - (blobuploader) - INFO - Iteration through files over. 04:59:31 INFO - Return code: 0 04:59:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:59:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:59:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/31b8dc01c9d20bb75cc30c066f38e82011533f2c31714620cb548951a49a53289f1ac7ae461ddeaa8bcc44315a52807248ebc7e9d00138e68a5310784d01082d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f1ddb9954f481e25411b588aea857eb1fb80fcc094093dc9a6b92202bfc3c492a74e2470fbb60474fc1b95a7de61cdb855c4669359b172cd236eaa3ef87c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c8b8311529c8e935cc60040df00a0f584be881b928f58356d0fdcc8b6289e216ec563b35fe6c0b2072aebd753490354af02a090900da9d23b7443f717fc87b47"} 04:59:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:59:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:59:31 INFO - Contents: 04:59:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/31b8dc01c9d20bb75cc30c066f38e82011533f2c31714620cb548951a49a53289f1ac7ae461ddeaa8bcc44315a52807248ebc7e9d00138e68a5310784d01082d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f1ddb9954f481e25411b588aea857eb1fb80fcc094093dc9a6b92202bfc3c492a74e2470fbb60474fc1b95a7de61cdb855c4669359b172cd236eaa3ef87c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c8b8311529c8e935cc60040df00a0f584be881b928f58356d0fdcc8b6289e216ec563b35fe6c0b2072aebd753490354af02a090900da9d23b7443f717fc87b47"} 04:59:31 INFO - Running post-run listener: copy_logs_to_upload_dir 04:59:31 INFO - Copying logs to upload dir... 04:59:31 INFO - mkdir: /builds/slave/test/build/upload/logs 04:59:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1228.261518 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 28 secs) (at 2016-08-02 04:59:30.695816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 04:59:30.699579) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 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/31b8dc01c9d20bb75cc30c066f38e82011533f2c31714620cb548951a49a53289f1ac7ae461ddeaa8bcc44315a52807248ebc7e9d00138e68a5310784d01082d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f1ddb9954f481e25411b588aea857eb1fb80fcc094093dc9a6b92202bfc3c492a74e2470fbb60474fc1b95a7de61cdb855c4669359b172cd236eaa3ef87c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c8b8311529c8e935cc60040df00a0f584be881b928f58356d0fdcc8b6289e216ec563b35fe6c0b2072aebd753490354af02a090900da9d23b7443f717fc87b47"} build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011179 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1470126604/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/31b8dc01c9d20bb75cc30c066f38e82011533f2c31714620cb548951a49a53289f1ac7ae461ddeaa8bcc44315a52807248ebc7e9d00138e68a5310784d01082d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/62f1ddb9954f481e25411b588aea857eb1fb80fcc094093dc9a6b92202bfc3c492a74e2470fbb60474fc1b95a7de61cdb855c4669359b172cd236eaa3ef87c28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c8b8311529c8e935cc60040df00a0f584be881b928f58356d0fdcc8b6289e216ec563b35fe6c0b2072aebd753490354af02a090900da9d23b7443f717fc87b47"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 04:59:30.725570) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:59:30.726096) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1470137941.579058-1142204173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 04:59:30.807236) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 04:59:30.807535) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 04:59:30.807927) ========= ========= Total master_lag: 0.15 =========