builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1470003355.59 results: success (0) buildid: 20160731123002 builduid: 2e9bde487c454ddcb68d8984514d6a4b revision: 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.592301) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.592659) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.592929) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.642455) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.642708) ========= 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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-31 15:15:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-07-31 15:15:55 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106755 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.768963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.769298) ========= 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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020699 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:55.820601) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-31 15:15:55.820908) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 --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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-31 15:15:56,044 truncating revision to first 12 chars 2016-07-31 15:15:56,044 Setting DEBUG logging. 2016-07-31 15:15:56,044 attempt 1/10 2016-07-31 15:15:56,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4a18b5cacb1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-31 15:15:56,707 unpacking tar archive at: autoland-4a18b5cacb1b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000193 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-31 15:15:56.842118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:56.842465) ========= script_repo_revision: 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:56.842847) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:56.843257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-31 15:15:56.857646) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-31 15:15:56.857914) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:15:57 INFO - MultiFileLogger online at 20160731 15:15:57 in /builds/slave/test 15:15:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:15:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:57 INFO - {'append_to_log': False, 15:15:57 INFO - 'base_work_dir': '/builds/slave/test', 15:15:57 INFO - 'blob_upload_branch': 'Autoland', 15:15:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:57 INFO - 'branch': 'Autoland', 15:15:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:57 INFO - 'config_files': ('talos/linux_config.py',), 15:15:57 INFO - 'default_actions': ('clobber', 15:15:57 INFO - 'read-buildbot-config', 15:15:57 INFO - 'download-and-extract', 15:15:57 INFO - 'populate-webroot', 15:15:57 INFO - 'create-virtualenv', 15:15:57 INFO - 'install', 15:15:57 INFO - 'run-tests'), 15:15:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:57 INFO - 'download_minidump_stackwalk': True, 15:15:57 INFO - 'download_symbols': 'ondemand', 15:15:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:57 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:57 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:57 INFO - 'installer_path': 'installer.exe', 15:15:57 INFO - 'log_level': 'info', 15:15:57 INFO - 'log_name': 'talos', 15:15:57 INFO - 'log_to_console': True, 15:15:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:15:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:15:57 INFO - 'opt_config_files': (), 15:15:57 INFO - 'pip_index': False, 15:15:57 INFO - 'sps_profile': False, 15:15:57 INFO - 'sps_profile_interval': 0, 15:15:57 INFO - 'suite': 'dromaeojs', 15:15:57 INFO - 'system_bits': '32', 15:15:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:57 INFO - 'title': 'talos-linux64-ix-021', 15:15:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:57 INFO - 'use_talos_json': True, 15:15:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:57 INFO - 'work_dir': 'build'} 15:15:57 INFO - [mozharness: 2016-07-31 22:15:57.172908Z] Running clobber step. 15:15:57 INFO - Running pre-action listener: _resource_record_pre_action 15:15:57 INFO - Running main action method: clobber 15:15:57 INFO - rmtree: /builds/slave/test/build 15:15:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:58 INFO - Running post-action listener: _resource_record_post_action 15:15:58 INFO - [mozharness: 2016-07-31 22:15:58.135720Z] Finished clobber step (success) 15:15:58 INFO - [mozharness: 2016-07-31 22:15:58.135821Z] Running read-buildbot-config step. 15:15:58 INFO - Running pre-action listener: _resource_record_pre_action 15:15:58 INFO - Running main action method: read_buildbot_config 15:15:58 INFO - Using buildbot properties: 15:15:58 INFO - { 15:15:58 INFO - "project": "", 15:15:58 INFO - "product": "firefox", 15:15:58 INFO - "script_repo_revision": "production", 15:15:58 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:15:58 INFO - "repository": "", 15:15:58 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 15:15:58 INFO - "buildid": "20160731123002", 15:15:58 INFO - "pgo_build": "True", 15:15:58 INFO - "basedir": "/builds/slave/test", 15:15:58 INFO - "buildnumber": 81, 15:15:58 INFO - "slavename": "talos-linux64-ix-021", 15:15:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:15:58 INFO - "platform": "ubuntu64_hw", 15:15:58 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 15:15:58 INFO - "branch": "autoland", 15:15:58 INFO - "repo_path": "integration/autoland", 15:15:58 INFO - "revision": "4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1", 15:15:58 INFO - "stage_platform": "linux64-pgo", 15:15:58 INFO - "builduid": "2e9bde487c454ddcb68d8984514d6a4b", 15:15:58 INFO - "slavebuilddir": "test" 15:15:58 INFO - } 15:15:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:15:58 INFO - Running post-action listener: _resource_record_post_action 15:15:58 INFO - [mozharness: 2016-07-31 22:15:58.138248Z] Finished read-buildbot-config step (success) 15:15:58 INFO - [mozharness: 2016-07-31 22:15:58.138338Z] Running download-and-extract step. 15:15:58 INFO - Running pre-action listener: _resource_record_pre_action 15:15:58 INFO - Running main action method: download_and_extract 15:15:58 INFO - mkdir: /builds/slave/test/build/tests 15:15:58 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')]} 15:15:58 INFO - https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:15:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:15:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:15:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:16:09 INFO - Downloaded 1361 bytes. 15:16:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:16:09 INFO - Using the following test package requirements: 15:16:09 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:16:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:16:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'jsshell-linux-x86_64.zip'], 15:16:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:16:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:16:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:16:09 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:16:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:16:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:16:09 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:16:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:16:09 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')]} 15:16:09 INFO - https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:16:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:16:16 INFO - Downloaded 21807816 bytes. 15:16:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:16:17 INFO - Return code: 0 15:16:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:16: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')]} 15:16:17 INFO - https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:16:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:16:18 INFO - Downloaded 21807816 bytes. 15:16:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:16:20 INFO - Return code: 0 15:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:20 INFO - https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:16:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:16:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:16:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:16:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:16:21 INFO - Downloaded 14413723 bytes. 15:16:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:16:21 INFO - Return code: 0 15:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:21 INFO - https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:16:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:16:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:16:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:16:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:16:23 INFO - Downloaded 59390830 bytes. 15:16:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:16:23 INFO - mkdir: /builds/slave/test/properties 15:16:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:23 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:23 INFO - Contents: 15:16:23 INFO - build_url:https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:16:24 INFO - Running post-action listener: _resource_record_post_action 15:16:24 INFO - Running post-action listener: set_extra_try_arguments 15:16:24 INFO - [mozharness: 2016-07-31 22:16:24.784133Z] Finished download-and-extract step (success) 15:16:24 INFO - [mozharness: 2016-07-31 22:16:24.784370Z] Running populate-webroot step. 15:16:24 INFO - Running pre-action listener: _resource_record_pre_action 15:16:24 INFO - Running main action method: populate_webroot 15:16:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:24 INFO - '--tppagecycles', 15:16:24 INFO - '7'], 15:16:24 INFO - 'tests': ['tsvgm']}}, 15:16:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:16:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['damp', 'tps']}, 15:16:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'tests': ['damp', 'tps']}, 15:16:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['dromaeo_dom']}, 15:16:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['basic_compositor_video']}, 15:16:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:16:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['a11yr', 15:16:24 INFO - 'ts_paint', 15:16:24 INFO - 'tpaint', 15:16:24 INFO - 'sessionrestore', 15:16:24 INFO - 'sessionrestore_no_auto_restore', 15:16:24 INFO - 'tabpaint']}, 15:16:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['tsvgx', 15:16:24 INFO - 'tsvgr_opacity', 15:16:24 INFO - 'tart', 15:16:24 INFO - 'tscrollx', 15:16:24 INFO - 'cart']}, 15:16:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:24 INFO - 'tsvgr_opacity', 15:16:24 INFO - 'tart', 15:16:24 INFO - 'tscrollx', 15:16:24 INFO - 'cart']}, 15:16:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'talos_options': ['--disable-e10s'], 15:16:24 INFO - 'tests': ['tp5o']}, 15:16:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'tests': ['tp5o']}, 15:16:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'talos_options': ['--disable-e10s', 15:16:24 INFO - '--xperf_path', 15:16:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:24 INFO - 'tests': ['tp5n']}, 15:16:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:24 INFO - 'talos_options': ['--xperf_path', 15:16:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:24 INFO - 'tests': ['tp5n']}}, 15:16:24 INFO - 'talos.zip': {'path': '', 15:16:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:24 INFO - Running post-action listener: _resource_record_post_action 15:16:24 INFO - [mozharness: 2016-07-31 22:16:24.804659Z] Finished populate-webroot step (success) 15:16:24 INFO - [mozharness: 2016-07-31 22:16:24.804748Z] Running create-virtualenv step. 15:16:24 INFO - Running pre-action listener: _resource_record_pre_action 15:16:24 INFO - Running main action method: create_virtualenv 15:16:24 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:24 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 15:16:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:24 INFO - Using real prefix '/usr' 15:16:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:25 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:28 INFO - Installing pip.................done. 15:16:28 INFO - Return code: 0 15:16:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:16:28 INFO - Using env: {'DISPLAY': ':0', 15:16:28 INFO - 'HOME': '/home/cltbld', 15:16:28 INFO - 'LANG': 'en_US.UTF-8', 15:16:28 INFO - 'LANGUAGE': 'en_US:en', 15:16:28 INFO - 'LOGNAME': 'cltbld', 15:16:28 INFO - 'MAIL': '/var/mail/cltbld', 15:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:28 INFO - 'MOZ_NO_REMOTE': '1', 15:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:28 INFO - 'NO_EM_RESTART': '1', 15:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:28 INFO - 'PWD': '/builds/slave/test', 15:16:28 INFO - 'SHELL': '/bin/bash', 15:16:28 INFO - 'SHLVL': '1', 15:16:28 INFO - 'TERM': 'linux', 15:16:28 INFO - 'TMOUT': '86400', 15:16:28 INFO - 'USER': 'cltbld', 15:16:28 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:28 INFO - '_': '/tools/buildbot/bin/python'} 15:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:28 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 15:16:28 INFO - Cleaning up... 15:16:28 INFO - Return code: 0 15:16:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:16:28 INFO - Using env: {'DISPLAY': ':0', 15:16:28 INFO - 'HOME': '/home/cltbld', 15:16:28 INFO - 'LANG': 'en_US.UTF-8', 15:16:28 INFO - 'LANGUAGE': 'en_US:en', 15:16:28 INFO - 'LOGNAME': 'cltbld', 15:16:28 INFO - 'MAIL': '/var/mail/cltbld', 15:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:28 INFO - 'MOZ_NO_REMOTE': '1', 15:16:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:28 INFO - 'NO_EM_RESTART': '1', 15:16:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:28 INFO - 'PWD': '/builds/slave/test', 15:16:28 INFO - 'SHELL': '/bin/bash', 15:16:28 INFO - 'SHLVL': '1', 15:16:28 INFO - 'TERM': 'linux', 15:16:28 INFO - 'TMOUT': '86400', 15:16:28 INFO - 'USER': 'cltbld', 15:16:28 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:28 INFO - '_': '/tools/buildbot/bin/python'} 15:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:28 INFO - Downloading/unpacking psutil>=3.1.1 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:30 INFO - Installing collected packages: psutil 15:16:30 INFO - Running setup.py install for psutil 15:16:30 INFO - building 'psutil._psutil_linux' extension 15:16:30 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 15:16:30 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 15:16:30 INFO - building 'psutil._psutil_posix' extension 15:16:30 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 15:16:30 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 15:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:30 INFO - Successfully installed psutil 15:16:30 INFO - Cleaning up... 15:16:30 INFO - Return code: 0 15:16:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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')]} 15:16:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:16: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 mozsystemmonitor==0.3 15:16:30 INFO - Using env: {'DISPLAY': ':0', 15:16:30 INFO - 'HOME': '/home/cltbld', 15:16:30 INFO - 'LANG': 'en_US.UTF-8', 15:16:30 INFO - 'LANGUAGE': 'en_US:en', 15:16:30 INFO - 'LOGNAME': 'cltbld', 15:16:30 INFO - 'MAIL': '/var/mail/cltbld', 15:16:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:30 INFO - 'MOZ_NO_REMOTE': '1', 15:16:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:30 INFO - 'NO_EM_RESTART': '1', 15:16:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:30 INFO - 'PWD': '/builds/slave/test', 15:16:30 INFO - 'SHELL': '/bin/bash', 15:16:30 INFO - 'SHLVL': '1', 15:16:30 INFO - 'TERM': 'linux', 15:16:30 INFO - 'TMOUT': '86400', 15:16:30 INFO - 'USER': 'cltbld', 15:16:30 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:30 INFO - '_': '/tools/buildbot/bin/python'} 15:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:16:33 INFO - Installing collected packages: mozsystemmonitor 15:16:33 INFO - Running setup.py install for mozsystemmonitor 15:16:33 INFO - Successfully installed mozsystemmonitor 15:16:33 INFO - Cleaning up... 15:16:33 INFO - Return code: 0 15:16:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:33 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:33 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 15:16:33 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 15:16:33 INFO - Using env: {'DISPLAY': ':0', 15:16:33 INFO - 'HOME': '/home/cltbld', 15:16:33 INFO - 'LANG': 'en_US.UTF-8', 15:16:33 INFO - 'LANGUAGE': 'en_US:en', 15:16:33 INFO - 'LOGNAME': 'cltbld', 15:16:33 INFO - 'MAIL': '/var/mail/cltbld', 15:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:33 INFO - 'MOZ_NO_REMOTE': '1', 15:16:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:33 INFO - 'NO_EM_RESTART': '1', 15:16:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:33 INFO - 'PWD': '/builds/slave/test', 15:16:33 INFO - 'SHELL': '/bin/bash', 15:16:33 INFO - 'SHLVL': '1', 15:16:33 INFO - 'TERM': 'linux', 15:16:33 INFO - 'TMOUT': '86400', 15:16:33 INFO - 'USER': 'cltbld', 15:16:33 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:33 INFO - '_': '/tools/buildbot/bin/python'} 15:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:33 INFO - Downloading/unpacking jsonschema==2.5.1 15:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:16:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:16:36 INFO - warning: no files found matching '*.txt' 15:16:36 INFO - no previously-included directories found matching 'build' 15:16:36 INFO - no previously-included directories found matching 'dist' 15:16:36 INFO - no previously-included directories found matching '.git*' 15:16:36 INFO - Installing collected packages: jsonschema, functools32 15:16:36 INFO - Running setup.py install for functools32 15:16:39 INFO - warning: no files found matching '*.txt' 15:16:39 INFO - no previously-included directories found matching 'build' 15:16:39 INFO - no previously-included directories found matching 'dist' 15:16:39 INFO - no previously-included directories found matching '.git*' 15:16:39 INFO - Successfully installed jsonschema functools32 15:16:39 INFO - Cleaning up... 15:16:39 INFO - Return code: 0 15:16:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:16:39 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')]} 15:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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')]} 15:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:39 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 15:16:39 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 15:16:39 INFO - Using env: {'DISPLAY': ':0', 15:16:39 INFO - 'HOME': '/home/cltbld', 15:16:39 INFO - 'LANG': 'en_US.UTF-8', 15:16:39 INFO - 'LANGUAGE': 'en_US:en', 15:16:39 INFO - 'LOGNAME': 'cltbld', 15:16:39 INFO - 'MAIL': '/var/mail/cltbld', 15:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:39 INFO - 'MOZ_NO_REMOTE': '1', 15:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:39 INFO - 'NO_EM_RESTART': '1', 15:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:39 INFO - 'PWD': '/builds/slave/test', 15:16:39 INFO - 'SHELL': '/bin/bash', 15:16:39 INFO - 'SHLVL': '1', 15:16:39 INFO - 'TERM': 'linux', 15:16:39 INFO - 'TMOUT': '86400', 15:16:39 INFO - 'USER': 'cltbld', 15:16:39 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:39 INFO - '_': '/tools/buildbot/bin/python'} 15:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:16:39 INFO - Cleaning up... 15:16:39 INFO - Return code: 0 15:16:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:39 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')]} 15:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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')]} 15:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:39 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 15:16:39 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 15:16:39 INFO - Using env: {'DISPLAY': ':0', 15:16:39 INFO - 'HOME': '/home/cltbld', 15:16:39 INFO - 'LANG': 'en_US.UTF-8', 15:16:39 INFO - 'LANGUAGE': 'en_US:en', 15:16:39 INFO - 'LOGNAME': 'cltbld', 15:16:39 INFO - 'MAIL': '/var/mail/cltbld', 15:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:39 INFO - 'MOZ_NO_REMOTE': '1', 15:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:39 INFO - 'NO_EM_RESTART': '1', 15:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:39 INFO - 'PWD': '/builds/slave/test', 15:16:39 INFO - 'SHELL': '/bin/bash', 15:16:39 INFO - 'SHLVL': '1', 15:16:39 INFO - 'TERM': 'linux', 15:16:39 INFO - 'TMOUT': '86400', 15:16:39 INFO - 'USER': 'cltbld', 15:16:39 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:39 INFO - '_': '/tools/buildbot/bin/python'} 15:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:40 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:42 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 INFO - Downloading docopt-0.6.1.tar.gz 15:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:42 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:42 INFO - Running setup.py install for blobuploader 15:16:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:42 INFO - Running setup.py install for requests 15:16:43 INFO - Running setup.py install for docopt 15:16:43 INFO - Successfully installed blobuploader requests docopt 15:16:43 INFO - Cleaning up... 15:16:43 INFO - Return code: 0 15:16:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:43 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:43 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 15:16:43 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 15:16:43 INFO - Using env: {'DISPLAY': ':0', 15:16:43 INFO - 'HOME': '/home/cltbld', 15:16:43 INFO - 'LANG': 'en_US.UTF-8', 15:16:43 INFO - 'LANGUAGE': 'en_US:en', 15:16:43 INFO - 'LOGNAME': 'cltbld', 15:16:43 INFO - 'MAIL': '/var/mail/cltbld', 15:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:43 INFO - 'MOZ_NO_REMOTE': '1', 15:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:43 INFO - 'NO_EM_RESTART': '1', 15:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:43 INFO - 'PWD': '/builds/slave/test', 15:16:43 INFO - 'SHELL': '/bin/bash', 15:16:43 INFO - 'SHLVL': '1', 15:16:43 INFO - 'TERM': 'linux', 15:16:43 INFO - 'TMOUT': '86400', 15:16:43 INFO - 'USER': 'cltbld', 15:16:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:43 INFO - '_': '/tools/buildbot/bin/python'} 15:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:43 INFO - Running setup.py (path:/tmp/pip-e7SOSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:43 INFO - Running setup.py (path:/tmp/pip-Hao_n3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:43 INFO - Running setup.py (path:/tmp/pip-noRwV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:43 INFO - Running setup.py (path:/tmp/pip-JqUF1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:43 INFO - Running setup.py (path:/tmp/pip-fdHRpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:44 INFO - Running setup.py (path:/tmp/pip-H6g5tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:44 INFO - Running setup.py (path:/tmp/pip-tbU7i6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:44 INFO - Running setup.py (path:/tmp/pip-lXg1oB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:44 INFO - Running setup.py (path:/tmp/pip-BO1kYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:44 INFO - Running setup.py (path:/tmp/pip-Y6YHXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:44 INFO - Running setup.py (path:/tmp/pip-QWutpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:44 INFO - Running setup.py (path:/tmp/pip-uDtNvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:44 INFO - Running setup.py (path:/tmp/pip-k1h3_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:44 INFO - Running setup.py (path:/tmp/pip-cNFHEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:44 INFO - Running setup.py (path:/tmp/pip-m_gOcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:44 INFO - Running setup.py (path:/tmp/pip-kjwr5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:44 INFO - Running setup.py (path:/tmp/pip-8ZuPQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:44 INFO - Running setup.py install for manifestparser 15:16:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:44 INFO - Running setup.py install for mozcrash 15:16:45 INFO - Running setup.py install for mozdebug 15:16:45 INFO - Running setup.py install for mozdevice 15:16:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Running setup.py install for mozfile 15:16:45 INFO - Running setup.py install for mozhttpd 15:16:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Running setup.py install for mozinfo 15:16:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Running setup.py install for mozInstall 15:16:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Running setup.py install for mozleak 15:16:45 INFO - Running setup.py install for mozlog 15:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:45 INFO - Running setup.py install for moznetwork 15:16:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Running setup.py install for mozprocess 15:16:46 INFO - Running setup.py install for mozprofile 15:16:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Running setup.py install for mozrunner 15:16:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Running setup.py install for mozscreenshot 15:16:46 INFO - Running setup.py install for moztest 15:16:46 INFO - Running setup.py install for mozversion 15:16:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:46 INFO - Cleaning up... 15:16:46 INFO - Return code: 0 15:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:46 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:46 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 15:16:46 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 15:16:46 INFO - Using env: {'DISPLAY': ':0', 15:16:46 INFO - 'HOME': '/home/cltbld', 15:16:46 INFO - 'LANG': 'en_US.UTF-8', 15:16:46 INFO - 'LANGUAGE': 'en_US:en', 15:16:46 INFO - 'LOGNAME': 'cltbld', 15:16:46 INFO - 'MAIL': '/var/mail/cltbld', 15:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:46 INFO - 'MOZ_NO_REMOTE': '1', 15:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:46 INFO - 'NO_EM_RESTART': '1', 15:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:46 INFO - 'PWD': '/builds/slave/test', 15:16:46 INFO - 'SHELL': '/bin/bash', 15:16:46 INFO - 'SHLVL': '1', 15:16:46 INFO - 'TERM': 'linux', 15:16:46 INFO - 'TMOUT': '86400', 15:16:46 INFO - 'USER': 'cltbld', 15:16:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:46 INFO - '_': '/tools/buildbot/bin/python'} 15:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:46 INFO - Running setup.py (path:/tmp/pip-8qhr5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:46 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)) 15:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:46 INFO - Running setup.py (path:/tmp/pip-iSnPCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:47 INFO - Running setup.py (path:/tmp/pip-jgj4Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:47 INFO - Running setup.py (path:/tmp/pip-aGW4uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:47 INFO - Running setup.py (path:/tmp/pip-CPFfsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:47 INFO - Running setup.py (path:/tmp/pip-m_gU9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:47 INFO - Running setup.py (path:/tmp/pip-XmN6sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:47 INFO - Running setup.py (path:/tmp/pip-b_3yYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:47 INFO - Running setup.py (path:/tmp/pip-g9fOEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:47 INFO - Running setup.py (path:/tmp/pip-XcRlDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:47 INFO - Running setup.py (path:/tmp/pip-gyou_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:47 INFO - Running setup.py (path:/tmp/pip-QzXmlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:47 INFO - Running setup.py (path:/tmp/pip-tExtwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:47 INFO - Running setup.py (path:/tmp/pip-9LqFRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:47 INFO - Running setup.py (path:/tmp/pip-46BFm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:47 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)) 15:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:47 INFO - Running setup.py (path:/tmp/pip-Rk7Sta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:48 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)) 15:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:48 INFO - Running setup.py (path:/tmp/pip-vbiZW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 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)) 15:16:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:50 INFO - Downloading blessings-1.6.tar.gz 15:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:50 INFO - Installing collected packages: blessings 15:16:50 INFO - Running setup.py install for blessings 15:16:50 INFO - Successfully installed blessings 15:16:50 INFO - Cleaning up... 15:16:50 INFO - Return code: 0 15:16:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:50 INFO - Reading from file tmpfile_stdout 15:16:50 INFO - Current package versions: 15:16:50 INFO - argparse == 1.2.1 15:16:50 INFO - blessings == 1.6 15:16:50 INFO - blobuploader == 1.2.4 15:16:50 INFO - docopt == 0.6.1 15:16:50 INFO - functools32 == 3.2.3-2 15:16:50 INFO - jsonschema == 2.5.1 15:16:50 INFO - manifestparser == 1.1 15:16:50 INFO - mozInstall == 1.12 15:16:50 INFO - mozcrash == 0.17 15:16:50 INFO - mozdebug == 0.1 15:16:50 INFO - mozdevice == 0.48 15:16:50 INFO - mozfile == 1.2 15:16:50 INFO - mozhttpd == 0.7 15:16:50 INFO - mozinfo == 0.9 15:16:50 INFO - mozleak == 0.1 15:16:50 INFO - mozlog == 3.3 15:16:50 INFO - moznetwork == 0.27 15:16:50 INFO - mozprocess == 0.23 15:16:50 INFO - mozprofile == 0.28 15:16:50 INFO - mozrunner == 6.12 15:16:50 INFO - mozscreenshot == 0.1 15:16:50 INFO - mozsystemmonitor == 0.3 15:16:50 INFO - moztest == 0.7 15:16:50 INFO - mozversion == 1.4 15:16:50 INFO - psutil == 3.1.1 15:16:50 INFO - requests == 1.2.3 15:16:50 INFO - wsgiref == 0.1.2 15:16:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:50 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 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:50 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 15:16:50 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 15:16:50 INFO - Using env: {'DISPLAY': ':0', 15:16:50 INFO - 'HOME': '/home/cltbld', 15:16:50 INFO - 'LANG': 'en_US.UTF-8', 15:16:50 INFO - 'LANGUAGE': 'en_US:en', 15:16:50 INFO - 'LOGNAME': 'cltbld', 15:16:50 INFO - 'MAIL': '/var/mail/cltbld', 15:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:50 INFO - 'MOZ_NO_REMOTE': '1', 15:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:50 INFO - 'NO_EM_RESTART': '1', 15:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:50 INFO - 'PWD': '/builds/slave/test', 15:16:50 INFO - 'SHELL': '/bin/bash', 15:16:50 INFO - 'SHLVL': '1', 15:16:50 INFO - 'TERM': 'linux', 15:16:50 INFO - 'TMOUT': '86400', 15:16:50 INFO - 'USER': 'cltbld', 15:16:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:50 INFO - '_': '/tools/buildbot/bin/python'} 15:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 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)) 15:16:50 INFO - Cleaning up... 15:16:50 INFO - Return code: 0 15:16:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x246b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25c9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, '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': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', '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 15:16:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:16:50 INFO - Using env: {'DISPLAY': ':0', 15:16:50 INFO - 'HOME': '/home/cltbld', 15:16:50 INFO - 'LANG': 'en_US.UTF-8', 15:16:50 INFO - 'LANGUAGE': 'en_US:en', 15:16:50 INFO - 'LOGNAME': 'cltbld', 15:16:50 INFO - 'MAIL': '/var/mail/cltbld', 15:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:50 INFO - 'MOZ_NO_REMOTE': '1', 15:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:16:50 INFO - 'NO_EM_RESTART': '1', 15:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:50 INFO - 'PWD': '/builds/slave/test', 15:16:50 INFO - 'SHELL': '/bin/bash', 15:16:50 INFO - 'SHLVL': '1', 15:16:50 INFO - 'TERM': 'linux', 15:16:50 INFO - 'TMOUT': '86400', 15:16:50 INFO - 'USER': 'cltbld', 15:16:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:50 INFO - '_': '/tools/buildbot/bin/python'} 15:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:51 INFO - Cleaning up... 15:16:51 INFO - Return code: 0 15:16:51 INFO - Running post-action listener: _resource_record_post_action 15:16:51 INFO - Running post-action listener: _start_resource_monitoring 15:16:51 INFO - Starting resource monitoring. 15:16:51 INFO - [mozharness: 2016-07-31 22:16:51.101480Z] Finished create-virtualenv step (success) 15:16:51 INFO - [mozharness: 2016-07-31 22:16:51.101914Z] Running install step. 15:16:51 INFO - Running pre-action listener: _resource_record_pre_action 15:16:51 INFO - Running main action method: install 15:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:51 INFO - Reading from file tmpfile_stdout 15:16:51 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:51 INFO - Reading from file tmpfile_stdout 15:16:51 INFO - Output received: 15:16:51 INFO - Usage: mozinstall [options] installer 15:16:51 INFO - Options: 15:16:51 INFO - -h, --help show this help message and exit 15:16:51 INFO - -d DEST, --destination=DEST 15:16:51 INFO - Directory to install application into. [default: 15:16:51 INFO - "/builds/slave/test"] 15:16:51 INFO - --app=APP Application being installed. [default: firefox] 15:16:51 INFO - mkdir: /builds/slave/test/build/application 15:16:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:17:08 INFO - Reading from file tmpfile_stdout 15:17:08 INFO - Output received: 15:17:08 INFO - /builds/slave/test/build/application/firefox/firefox 15:17:08 INFO - Running post-action listener: _resource_record_post_action 15:17:08 INFO - [mozharness: 2016-07-31 22:17:08.264291Z] Finished install step (success) 15:17:08 INFO - [mozharness: 2016-07-31 22:17:08.264572Z] Running run-tests step. 15:17:08 INFO - Running pre-action listener: _resource_record_pre_action 15:17:08 INFO - Running main action method: run_tests 15:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:08 INFO - Python 2.7.3 15:17:08 INFO - Return code: 0 15:17:08 INFO - grabbing minidump binary from tooltool 15:17:08 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')]} 15:17:08 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 0x25c73b0>, '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 0x25c82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:08 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 15:17:08 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 15:17:08 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 15:17:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:08 INFO - Return code: 0 15:17:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:17:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:17:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 15:17:08 INFO - Using env: {'DISPLAY': ':0', 15:17:08 INFO - 'HOME': '/home/cltbld', 15:17:08 INFO - 'LANG': 'en_US.UTF-8', 15:17:08 INFO - 'LANGUAGE': 'en_US:en', 15:17:08 INFO - 'LOGNAME': 'cltbld', 15:17:08 INFO - 'MAIL': '/var/mail/cltbld', 15:17:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:08 INFO - 'MOZ_NO_REMOTE': '1', 15:17:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:17:08 INFO - 'NO_EM_RESTART': '1', 15:17:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:08 INFO - 'PWD': '/builds/slave/test', 15:17:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:08 INFO - 'SHELL': '/bin/bash', 15:17:08 INFO - 'SHLVL': '1', 15:17:08 INFO - 'TERM': 'linux', 15:17:08 INFO - 'TMOUT': '86400', 15:17:08 INFO - 'USER': 'cltbld', 15:17:08 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176', 15:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:08 INFO - '_': '/tools/buildbot/bin/python'} 15:17:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 15:17:08 INFO - mozversion application_buildid: 20160731123002 15:17:08 INFO - mozversion application_changeset: 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 15:17:08 INFO - mozversion application_display_name: Nightly 15:17:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:08 INFO - mozversion application_name: Firefox 15:17:08 INFO - mozversion application_remotingname: firefox 15:17:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:17:08 INFO - mozversion application_vendor: Mozilla 15:17:08 INFO - mozversion application_version: 50.0a1 15:17:08 INFO - mozversion platform_buildid: 20160731123002 15:17:08 INFO - mozversion platform_changeset: 4a18b5cacb1b21a3e8b4b1dada6b2dd3dba51cb1 15:17:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:17:08 INFO - mozversion platform_version: 50.0a1 15:17:08 INFO - using testdate: 1470003428 15:17:08 INFO - actual date: 1470003428 15:17:08 INFO - starting webserver on 'localhost:56141' 15:17:08 INFO - SUITE-START | Running 2 tests 15:17:08 INFO - TEST-START | dromaeo_css 15:17:08 INFO - operating with platform_type : linux_ 15:17:08 INFO - Initialising browser for dromaeo_css test... 15:17:08 INFO - TEST-INFO | started process 29140 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqV37xN/profile http://localhost:56141/getInfo.html) 15:17:14 INFO - PROCESS | 29140 | __metrics Screen width/height:1600/1200 15:17:14 INFO - PROCESS | 29140 | colorDepth:24 15:17:14 INFO - PROCESS | 29140 | Browser inner width/height: 1024/697 15:17:14 INFO - PROCESS | 29140 | __metrics 15:17:15 INFO - PROCESS | 29140 | 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" 15:17:15 INFO - PROCESS | 29140 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:17:15 INFO - PROCESS | 29140 | console.error: PushService: 15:17:15 INFO - PROCESS | 29140 | stateChangeProcessEnqueue: Error transitioning state 15:17:15 INFO - PROCESS | 29140 | UnknownError 15:17:16 INFO - TEST-INFO | 29140: exit 0 15:17:16 INFO - Browser initialized. 15:17:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:16 INFO - TEST-INFO | started process 29217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqV37xN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:27 INFO - PROCESS | 29217 | RSS: Main: 155906048 15:17:27 INFO - PROCESS | 29217 | 15:18:18 INFO - PROCESS | 29217 | 1470003498759 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] 15:20:27 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56141/tests/dromaeo/cssquery-ext.html) 15:20:29 INFO - PROCESS | 29217 | RSS: Main: 264220672 15:20:29 INFO - PROCESS | 29217 | 15:23:31 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-ext.html (next: http://localhost:56141/tests/dromaeo/cssquery-jquery.html) 15:23:31 INFO - PROCESS | 29217 | RSS: Main: 248524800 15:23:31 INFO - PROCESS | 29217 | 15:26:33 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56141/tests/dromaeo/cssquery-mootools.html) 15:26:34 INFO - PROCESS | 29217 | RSS: Main: 187101184 15:26:34 INFO - PROCESS | 29217 | 15:29:36 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56141/tests/dromaeo/cssquery-prototype.html) 15:29:37 INFO - PROCESS | 29217 | RSS: Main: 236359680 15:29:37 INFO - PROCESS | 29217 | 15:32:39 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56141/tests/dromaeo/cssquery-yui.html) 15:32:40 INFO - PROCESS | 29217 | RSS: Main: 183779328 15:32:40 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | Cycle 1(1): loaded http://localhost:56141/tests/dromaeo/cssquery-yui.html (next: http://localhost:56141/tests/dromaeo/cssquery-dojo.html) 15:35:43 INFO - PROCESS | 29217 | RSS: Main: 277680128 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | __start_tp_report 15:35:43 INFO - PROCESS | 29217 | _x_x_mozilla_page_load 15:35:43 INFO - PROCESS | 29217 | _x_x_mozilla_page_load_details 15:35:43 INFO - PROCESS | 29217 | |i|pagename|runs| 15:35:43 INFO - PROCESS | 29217 | |0;dojo.html;2993.3713920076784;3037.5595538646885;3044.147638661175;3063.5711000459924;3071.355015446756;5211.687298762067;5281.181416880385;5290.365156181254;5357.946420535798;5387.919181212285;5262.158054711247;5321.813736519222;5336.706481143536;5364.275822763926;5369.704666243355;4800.039992001605;4840.128776820171;4865.975670121645;4880.243562247857;4962.478939711333;4886;4904.092742842586;4933.555979961803;4948.628852836052;4963.057019166365;4768.523147685239;4771.212749896263;4784.354112194844;4806.206975848981;4907.067657145149;4797.496262892416;4798.352222449959;4822.42130944289;4828.637852161067;4921.163402221595;4825.710457372569;4841.419029716457;4844.2733589961435;4863.465018847924;4884.462709101996;5108.2848401223855;5220.582353411718;5280.260763493114;5303.814366497154;5394.02907476654;5657.377688454267;5659.320881494205;5664.631798933097;5666;5700.800471983503;5738.598298119134;5749.712514374264;5750.223719797816;5755.798547050833;5766.942330576682;5179.249008893689;5264.789408423697;5312.468753124656;5314.229436731653;5381.919271210857;5908;5917.260342457193;5918.911216331759;5920.348761636216;5920.851978700567;5654.349749778746;5660.462256085665;5664.348599910981;5665.716714164275;5666.433356664383;5532.5573954083575;5602.551795856321;5617.747201375948;5641.210230567724;5730.627509211888;5611.831645050655;5615.382307946123;5616.9157462638095;5679.148127780783;5687.7156142192725;5598.412166722435;5603.691796951125;5644.125160600113;5653.604247702704;5656.91514627281;4272.230998420314;4280.186764514732;4281.357796330575;4297.613214810682;4396.3625274335045;3848.3842585186794;3857.0550215197454;3877.205172939597;3884.669803066771;3904.06302487401;5362.651427657268;5364.168553874156;5366.168243922199;5370.060239458157;5403.756830942617;5509.669419834822;5617.606767526234;5638.182463542763;5650.434956504317;5682.516986056149;5396.028714831289;5406.567474602022;5415.972920135374;5440.91838622421;5446;4956.10790057793;4963.875903102451;4963.925541116886;4968.850934471971;5000.574951129122;5678.034734095214;5815.82552523425;5906.084556893774;5911.965406053923;5928.140419639129;5706.115552089433;5712.400197979152;5719.599628025998;5757.337906140848;5768.134779783066;5897.469227769521;5897.852553686193;5899.46904778572;5907.5273978080795;5942.435468630473;5530;5541.445855414427;5546.306711661043;5552.222688823649;5579.8884022320135;5678.943210567842;5721.456461636138;5727.8568035799435;5754.53963682896;5778.16216648592;5523.4200408956485;5558.833235002957;5564.10974244119;5576.1356989666665;5586.16207568868;5428.402875683592;5506.366767821752;5515.641483303572;5568.16477528374;5579.4420557945505;5494.587905907153;5497.862553436196;5501.037318469332;5536.474034966672;5552;5594.468525490072;5596.796688711946;5632.633878797865;5633.746481408429;5649;5309.150535914235;5326.786928522815;5379.381371142369;5469.097598896137;5469.288992430958 15:35:43 INFO - PROCESS | 29217 | |1;ext.html;601.575888998256;623.8409205652558;644.1561554363784;649.7043845050503;663.0352836622715;33477.33045339095;44612.33081503776;45362;45461.545384546145;45524.31713524291;46655.53344466554;47005.529944700545;47054.529454705444;47071.11715531375;47102.52897471024;8860.911390886089;9006;9006.234470070052;9010.279177665803;9012.27901767857;8634.136586341347;8738.737837864874;8739.912600874006;8741.868871966926;8743.03826579079;8749.431286966326;8784.07767184446;8788.64845406183;8888.466691998501;8892.555372231394;8821.955890220572;8850.026497085348;8854.513001784899;8868.29053675711;8874.511901845395;8836;8909.821803563893;8910.955445222797;8918.598663060178;8926.866097008486;43700;44056.339154912705;44072;44079.11841763179;44187.55812441867;3505.1938054247667;3659.0852286928266;3785.1483416231404;3913.941290880639;4119.588041195856;30303;30782.53826192609;30934.53598196029;30977.91577294784;34192.48711269333;3920.4511368408444;4064.6138616831354;4069.8982525436804;4084.0402505411503;4168.91662166758;351399;351667;354762;355250;356176;54592.45407545953;54723.45276547263;54781.72609136929;54839.72580137074;54941.72529137409;61700.69149654313;62098.689506552175;62157.37842621606;62224.68887655623;62274.68862655658;17043.573910652332;17271.91364043197;17274.58155858181;17284.22221000058;17293.740593891103;13351.732965340832;13422.731545369037;13445.932770336085;13451.991100667294;13456.663583410298;16808.66382672364;17651.117444127998;17681.469555913343;17698.469045928643;17744.91127544354;6612.603243805387;6675.7997260082275;6730.663466826639;6733.259341472434;6777.220619628707;954.7183580843667;1049.2917280835406;1067.530286673861;1075.8816530181673;1121.0919155484085;32816;32934.03593676581;33266.66733332635;33360;33670.8316458421;1059.8463222832645;1065.658989123473;1092.2408925796653;1110.4558766204664;1161.9825702614298;16753.225330780668;17405.65188696219;17410.21654025572;17562;17726.20232089559;546678;550972;551087;551663;553506;60730.69634651887;60879;61073;61145.38854611486;61199.16402507932;1279.174932168746;1356.3828458051562;1363.7340718559785;1368.7125703602355;1369.5069774880835;31418.37163256768;32026.519602206452;32875;33711.32577348488;34633.30733385268;58968.115478267006;59414;59438.40561594329;59580.70209648924;59589.70205148947;477199;479201;481874;482484;483573;28528;28650.57024144681;28977.840886365153;29042.564361534172;29090.7090929088;50062.499375005784;50601;50855.74572127116;51518.484815151365;51960.74019629878;42755.14489710251;44476.332855006556;44497.55502444934;44665.77667111644;45289.773551132035;42875.78562107169;43130.56869431266;43579;44466.77766611146;44643.77678111719;36265.637343627284;37205.81397092997;38971;43557;43942.780286098365;34445.82777086098;38417.80791096027;38579.80710096432;39369;39733.40399893946;28862.567061494516;29302.70697293;29345.85327073351;29537.2615684601;29795.851020745624 15:35:43 INFO - PROCESS | 29217 | |2;jquery.html;368.1532475306794;386.3798603241797;387.9224155168966;401.6846775281401;404.89472737088346;18674.253029878804;19977.500562485944;20031.298904538344;20035.799642003574;20091.59816803665;18427;18718.34485792994;18976.715349269736;19147.808521914776;19215.423537293904;5592.636478628876;5721.942780572203;5869.354371019184;5907.43879331465;5934.703264836762;5915.438033386843;5942.792002279921;5953.107033944922;5953.136795164699;5955.553333499983;5913.2608423947;5946.375630558794;6543;6597.274299827015;6672.499562532806;6016;6652.467802575807;6664.633445160514;6699.933000669979;6706.061151438778;5728.713564321767;5776.191333213354;5780.364159942403;5784.971075144639;5785.537157027427;13640.727185456235;13855.792163117561;21698.56602867934;22394.32817015492;22464.550708985727;2262.7850354216325;2269.1490690990877;2534.2650631316897;2854.957175642367;2952.46855565998;9674.322797404184;9794.363366381162;9934.205263578897;10123.949380253052;10422.322549034365;1810.3301778341965;1904.8475672218246;1967.5572996075912;1979.3171355882341;1986.4040787763613;83049.58475207585;83859.87191088765;84168.57915710383;84169;84913;9186.724398267928;9597.952010240044;9856.901430985741;9871.358361706467;10497;12966.286854222919;13036.348182590833;13184.208947463183;13195.340232988505;13213.405396757169;7787.3770098392;7795.688172473151;7899.09160446556;7934.523928564305;7985;7935.444518883623;7977.840443191104;8114.675412983414;8160.551169685606;8166.877496837552;3945.881623551298;4077.5922407758985;4095.7542547447247;4097.426360309559;4208.894777630584;1799.4601619514094;1825.5709908171568;1952.3947576251408;1968.6653268944028;1991.32295019694;881.1628952495155;976.3653625143571;989.2036910287288;995.2585323933715;1041.2190856857358;19769.60460790776;20229.898850505655;20299.188032478833;20364.592708145756;20390.79609203889;954.1317401164906;982.9115379615725;989.6734077754323;1018.6231094494937;1032.0659802878406;10818;11132.721681957853;11136.33182009082;11170.050545703543;11186.105111591216;525813;526197;531276;534285;536212;83425;88758.55620721856;89282.55358723295;91304.54347728219;103189;1020.7601213714771;1054.409530662816;1056.0917610854658;1057.3338796558119;1063.0538820449806;17653.646927061644;17940.641187175923;18342.724859126858;18689.53276168107;18709.90645046766;43818.56181438272;44024;44913;44936.550634494546;45124.32313515365;62117.37882621116;62258.37741622707;62511;62842.68578657077;64161;7661.463697541119;8145.7963550909335;8147.266745992652;8271.710490132817;8843.734687959372;7847.921520784719;12212.328321942023;12867.549635762703;13684.247366394944;13787.448502059806;11872.584459543874;11969.521219151136;12175.573854915036;12193.69515762113;12212.633620991384;11399.829002565135;11601.709957251136;11652.533898644298;11672.824907626222;12075.73204813517;11639.825402618799;11989.280643161439;12136.817947730615;12258.141930065167;12266.693332666755;8113;8240.587970601566;8320.37597180226;8355.248027677539;8423.073461919306;11361.147913906656;11453.312801231952;11650.825237621273;11763.705907352385;11853.051755859684 15:35:43 INFO - PROCESS | 29217 | |3;mootools.html;1315.4080663701336;1436.4972259709102;1439.9712005759884;1536.661934374438;1743.058748275931;8426.15738426157;8888.688895888645;8930.508822014795;9158.267338612912;9161.221296189815;8959.238464730506;9098.363114581982;9099.499527525983;9099.59051842667;9101.772455688611;2359.964600530989;2383.9284821455385;2386;2387.582173119697;2389.4026493376655;2300.424893776556;2344.648302754592;2379.0245999140357;2381.7975472084895;2384.582698027847;2265.6714776357417;2286.702728645274;2304.5045315257216;2310.734265559457;2313.4447732544236;2273.5566564519927;2304.3893368257427;2308.203669733939;2309.1340747219792;2322.059565875823;2276.5105502316915;2284.0749496453964;2284.6344584866506;2285.714285714286;2296.127471560813;8851;8992.865107023334;9153.679621213225;9162.587683554257;9172.6789562365;5077.949220507821;5223.216517522402;5269.947300526984;5270.341207349082;5271.762770675329;3340.0146956647845;3347.9497807532907;3400.778949368308;3423.1613254752497;3428.668505416316;984.3454103021483;997.4015590645627;998.8751406074241;1009.6466236817129;1010.5048526222172;12489.375531223493;12602.81095783564;12713;12722.745545089047;12750.553730619476;4373.453318335208;4512.774361281989;4621.214393553104;4623.190941585209;4654.069186162781;10816.729581760519;10819.83770243447;10826.837597436044;10831.350118992885;10846.457677116112;5290.894182116336;5299.682019078868;5308.681479111266;5316.946830531722;5316.973415132899;4148.274052040881;4200.390943313202;4322.697411181187;4684.531546845288;4896.485868983706;4012.61880121388;4029.7783621901076;4132.40080188371;4162.979185104055;4203.936940945889;1219.5975328141692;1254.9184303020274;1258.3205069262679;1266.3288457117742;1267.1700036476122;969.2682025071026;972.7616733900239;983.4640121133856;1003.1623594298696;1003.2926786615419;5858;5987.341392446828;6196.597221180699;6280.780172694025;6296.464800492009;964.8600952861937;995.6564985080136;996.0736515041082;998.495759641391;1030.2427715629008;4248.532661407243;4292.12011537634;4330.7401555906745;4415.116976604692;4417.889552761142;11067.50196241155;11462.713432164095;11475.19673622855;11483.655490335303;11484.655460336204;11369.204155709187;11383.658490245305;11472.713182170513;11474.082073434105;11486.712832179095;7795.4543181976715;8112.675492980215;8133.7153199638915;8327.042390125092;8512.574371281411;5953.910691339547;6175.475084617858;6235.407636274546;6250.124982502362;6394.392532709385;10304.021117993778;10465.529051192563;10478.738031549272;10564.418956957134;10647.041766241075;9574.377665451724;10614.416207108683;10838.458077096271;10847.511861966073;10859.891401085888;7071.82320441993;7721.305082542598;7953.125156232694;8020.879686804819;8217.096119426978;8321.334293256647;8593.95703021481;8607.225349718556;8785;8870;7812.687492500237;7832.216778322122;7864.72473463426;7886.211378862068;8063;7672.309492145734;7726.1114971776715;7729.149793522821;7761.92238077612;7764.301212890641;7068.505204635626;7152.642367881481;7176.928230717626;7195.46034047459;7233.963830180815;5295.947040529545;5306.28365170697;5410.323709536308;5436.8640783979135;5518.199861020049;5284.550813180923;5365.302510673588;5370.812021579226;5415.48552887475;5480 15:35:43 INFO - PROCESS | 29217 | |4;prototype.html;293.5098385695889;314.7890913088231;316.52837272464023;316.96988786065344;321.6622546326357;5117.592662017947;5179.896402071956;5234.659747116435;5235.795766973599;5237.554807841338;5161.754636733014;5167.509086636764;5169.224616307556;5170.870728231796;5242.108841496945;4092.877213683579;4094.7338423002484;4095;4096.672266218696;4113.095119073799;4063.4514340564087;4104.630583247507;4119.237940980913;4130.112025914428;4134.896627584304;3890.2997460457104;4026.2148880968225;4042.110735638155;4076.266272071026;4098.180363927211;4036.8183431745642;4051.270771261172;4073.307537718595;4091.5499295077516;4103.148311923214;3592.6946209572216;3596.029072150505;3604.711623070148;3606.242689035306;3615.1142969972525;4967.975160124176;5024.497550244946;5036.84889453317;5060.645754797166;5063.8480845574695;4606.30905364198;4628.4445866496235;4637.397138372024;4653.440607665804;4657.976710116462;5345.465453454663;5347.732613369316;5362;5364.436734142898;5426.782928682848;3666.633336666339;3846.442265871461;3936.8031598420253;3954.8813535593977;3962.5641179470235;5321.946780532223;5399.217113518518;5574.386817450078;5592.944070559323;5594.552435805126;3905.453236546886;3909.4331321958725;3915.686745060388;3937.2322397132284;3944.74359166659;3833.1375440525658;3981.1042515433796;3996.0219691252437;3998.4202290668272;4000.19996000801;4523.434570678665;4525.705829121097;4538.205813982539;4560.703554268962;4589.173948689201;5354.518093371616;5387.488188622075;5390.8921821563345;5399.352077750695;5409.404965453797;1336.4988129451456;1352.6888815572277;1362.284800479756;1370.8347904281281;1375.7111006688508;870.9433886797337;873.8683404990552;884.9469031857981;893.9687110951086;897.3763234552055;4720.465848599219;4735.360726301905;4750.049990002013;4763.21379482694;4820.686070686091;4982.57996471004;4983.950160498349;4984.900301994012;4997.525235102659;5014.674046186986;4646.024334889644;4681.321208424827;4994.300797888299;5309.36287645485;5326.777299733682;2939.2798764302884;2946.7347938685193;2948.248196709825;2956.985215073954;2969.109267219869;5712.914306285409;5825.912611310834;5832.533397328203;5848.695487049803;5896.174535565025;5628.718564071862;5632.183908045977;5662.518685911661;5679.261695979496;5714.885702285931;5706.9714651426475;5766.625169363978;5791.8552036199435;5801.274840644919;5812.3025236971835;5323;5535.4187810280955;5576.91634625489;5588.38527761954;5597.6641401516035;5292.7353632319;5369.973150134224;5524.14375771756;5551.278333816578;5558.805441809517;5663.801766938137;5758.193852860519;5774.48029677331;5774.82675519735;5794.449527294901;3925.7644541327613;3933.6066393360434;3948.3485224937563;3948.874570747323;3961.3265744822875;5374.220737993047;5435.701036443033;5540.889182216414;5602.9719851402115;5631.071842641885;5414.918776218281;5427.918581221364;5438.1238472726845;5442.292501974718;5444.401115877329;5395.4083545355;5436.0758671026815;5452.26394436746;5460.26286451324;5473.2063850742215;5254.816081437131;5301.973490132525;5332.493413125747;5342.610921160449;5369.4630536946;5431.402545720047;5461.699606521679;5486.478784515464;5492.203630473638;5493.093639549589;5083.694978301313;5319.281896943863;5341.385740639721;5362.946370536245;5383.411893491351 15:35:43 INFO - PROCESS | 29217 | |5;yui.html;758.8330567275201;771.2634434115419;774.9651265693043;809.2999555384587;819.9713010044646;15601.985870918397;16326.510204693863;16396.590085247844;16411.507654770347;16436.096014719187;14286.928565357159;14986.550403487912;15091.54725358241;15122.017068890515;15152.090874547508;812.6546217857431;829.2122483640535;832.4422636833306;832.7376876283981;833.1543483364389;659.3867703036175;790.1584812175015;816.8202995341045;818.1409520003972;827.6192951242435;852.1393392673414;853.6798700487317;854.786303424144;855.3627547477138;855.7860534866284;850.4429598612857;853.5518852602371;853.6158728572167;853.7268074216254;854.406187699549;501.323074316411;602.8251806975971;613.9355367686418;620.5842085802523;623.3548277532732;14334.784978225334;14375.784363234456;14402.351894164683;14480.565583032527;14487.348069336906;14014.439422423193;14314.284285785669;14768.630784230372;14974.47589334368;15132.621684457867;13462;13568.525101621395;13756.105853119516;14036.578902632835;14253.857461425358;4527.750973696446;4671.439427268716;4717.339572459859;4773.158307599104;4801.471838097841;84827.5758621203;84942.99999999939;85757;85829;85976;11617.76764464706;11647.12646551512;11693.532258709556;12093.395330233629;12355;21708.78291217099;21718;21737.78262217389;21789.23737669174;21840.453988650093;8585;8848.690295839615;8887.111288871061;8895.110488951183;8929.553522323988;4874.195757699939;4905.386826646669;4948.505149485023;4954.207326827763;4954.281629163751;3429.3655673700446;3555.591107022674;3561.519194908706;3562.750607457505;3564.108972756811;591.4913174669781;635.6154526511486;635.7171058878847;636.7994081864198;637.3499030988459;594.3759052994338;602.5962605054624;721.5236356057549;741.7774667599698;756.4364548411359;9378;9815.803683926282;9901.653442129635;10063.698089057194;10165.51867982349;625.6308777821016;680.5950459476603;695.9838635592092;704.5561296383246;720.2293545905831;4188.204241194163;4515.164694531522;4620;4653.95346046542;4738.526147385234;65660.6716966412;73664.63167684128;105661.47169264105;126272;134164;64789.35210647927;66821.66589167023;73896;112923.4353828242;117749.41125294319;97870.27686400864;99809;100794.49602751939;100860.99139008662;102462;8754.292912882182;8768.34237432208;8778.517181555077;8835.33734969867;8873.778155545904;56055.43944560502;56255;57284.71357643352;57611.71194144002;58172.92789504694;80921.59539202266;83700.2334894919;86568.56715716633;87223.12776872404;87843.56078219568;11670.649880503599;11880.227785193934;11928.4035798208;12032.578859740217;12387;12644;13080.502675127535;13139.14595551286;13440.316968885296;14102.36539355752;12962.611121666365;13014.934925325313;13087.345632718136;13357.732845343233;13420.798688019882;12815.743685126432;13225.140365876186;13232.206067635972;13568.796468053184;13611.659708507366;12371.443285052374;12923.418446169757;12944.352782360656;13245.86754132485;13261.204327740366;10233.897661023295;10468.371897686164;10545.525451354553;10832.891671083189;10859.402732849769;10092.24308176875;10603.94698026505;10665.146788257076;10715.142788577052;10751.838722419014 15:35:43 INFO - PROCESS | 29217 | __end_tp_report 15:35:43 INFO - PROCESS | 29217 | __start_cc_report 15:35:43 INFO - PROCESS | 29217 | _x_x_mozilla_cycle_collect,994 15:35:43 INFO - PROCESS | 29217 | __end_cc_report 15:35:43 INFO - PROCESS | 29217 | __startTimestamp1470004543643__endTimestamp 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | ------- Summary: start ------- 15:35:43 INFO - PROCESS | 29217 | Number of tests: 6 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#0] dojo.html Cycles:165 Average:5283.83 Median:5497.86 stddev:603.85 (11.0%) stddev-sans-first:578.35 15:35:43 INFO - PROCESS | 29217 | Values: 2993.4 3037.6 3044.1 3063.6 3071.4 5211.7 5281.2 5290.4 5357.9 5387.9 5262.2 5321.8 5336.7 5364.3 5369.7 4800.0 4840.1 4866.0 4880.2 4962.5 4886.0 4904.1 4933.6 4948.6 4963.1 4768.5 4771.2 4784.4 4806.2 4907.1 4797.5 4798.4 4822.4 4828.6 4921.2 4825.7 4841.4 4844.3 4863.5 4884.5 5108.3 5220.6 5280.3 5303.8 5394.0 5657.4 5659.3 5664.6 5666.0 5700.8 5738.6 5749.7 5750.2 5755.8 5766.9 5179.2 5264.8 5312.5 5314.2 5381.9 5908.0 5917.3 5918.9 5920.3 5920.9 5654.3 5660.5 5664.3 5665.7 5666.4 5532.6 5602.6 5617.7 5641.2 5730.6 5611.8 5615.4 5616.9 5679.1 5687.7 5598.4 5603.7 5644.1 5653.6 5656.9 4272.2 4280.2 4281.4 4297.6 4396.4 3848.4 3857.1 3877.2 3884.7 3904.1 5362.7 5364.2 5366.2 5370.1 5403.8 5509.7 5617.6 5638.2 5650.4 5682.5 5396.0 5406.6 5416.0 5440.9 5446.0 4956.1 4963.9 4963.9 4968.9 5000.6 5678.0 5815.8 5906.1 5912.0 5928.1 5706.1 5712.4 5719.6 5757.3 5768.1 5897.5 5897.9 5899.5 5907.5 5942.4 5530.0 5541.4 5546.3 5552.2 5579.9 5678.9 5721.5 5727.9 5754.5 5778.2 5523.4 5558.8 5564.1 5576.1 5586.2 5428.4 5506.4 5515.6 5568.2 5579.4 5494.6 5497.9 5501.0 5536.5 5552.0 5594.5 5596.8 5632.6 5633.7 5649.0 5309.2 5326.8 5379.4 5469.1 5469.3 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#1] ext.html Cycles:180 Average:62726.53 Median:44064.17 stddev:124427.46 (282.4%) stddev-sans-first:124689.08 15:35:43 INFO - PROCESS | 29217 | Values: 601.6 623.8 644.2 649.7 663.0 33477.3 44612.3 45362.0 45461.5 45524.3 46655.5 47005.5 47054.5 47071.1 47102.5 8860.9 9006.0 9006.2 9010.3 9012.3 8634.1 8738.7 8739.9 8741.9 8743.0 8749.4 8784.1 8788.6 8888.5 8892.6 8822.0 8850.0 8854.5 8868.3 8874.5 8836.0 8909.8 8911.0 8918.6 8926.9 43700.0 44056.3 44072.0 44079.1 44187.6 3505.2 3659.1 3785.1 3913.9 4119.6 30303.0 30782.5 30934.5 30977.9 34192.5 3920.5 4064.6 4069.9 4084.0 4168.9 351399.0 351667.0 354762.0 355250.0 356176.0 54592.5 54723.5 54781.7 54839.7 54941.7 61700.7 62098.7 62157.4 62224.7 62274.7 17043.6 17271.9 17274.6 17284.2 17293.7 13351.7 13422.7 13445.9 13452.0 13456.7 16808.7 17651.1 17681.5 17698.5 17744.9 6612.6 6675.8 6730.7 6733.3 6777.2 954.7 1049.3 1067.5 1075.9 1121.1 32816.0 32934.0 33266.7 33360.0 33670.8 1059.8 1065.7 1092.2 1110.5 1162.0 16753.2 17405.7 17410.2 17562.0 17726.2 546678.0 550972.0 551087.0 551663.0 553506.0 60730.7 60879.0 61073.0 61145.4 61199.2 1279.2 1356.4 1363.7 1368.7 1369.5 31418.4 32026.5 32875.0 33711.3 34633.3 58968.1 59414.0 59438.4 59580.7 59589.7 477199.0 479201.0 481874.0 482484.0 483573.0 28528.0 28650.6 28977.8 29042.6 29090.7 50062.5 50601.0 50855.7 51518.5 51960.7 42755.1 44476.3 44497.6 44665.8 45289.8 42875.8 43130.6 43579.0 44466.8 44643.8 36265.6 37205.8 38971.0 43557.0 43942.8 34445.8 38417.8 38579.8 39369.0 39733.4 28862.6 29302.7 29345.9 29537.3 29795.9 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#2] jquery.html Cycles:180 Average:30265.48 Median:403.29 stddev:87427.85 (21678.7%) stddev-sans-first:87644.28 15:35:43 INFO - PROCESS | 29217 | Values: 368.2 386.4 387.9 401.7 404.9 18674.3 19977.5 20031.3 20035.8 20091.6 18427.0 18718.3 18976.7 19147.8 19215.4 5592.6 5721.9 5869.4 5907.4 5934.7 5915.4 5942.8 5953.1 5953.1 5955.6 5913.3 5946.4 6543.0 6597.3 6672.5 6016.0 6652.5 6664.6 6699.9 6706.1 5728.7 5776.2 5780.4 5785.0 5785.5 13640.7 13855.8 21698.6 22394.3 22464.6 2262.8 2269.1 2534.3 2855.0 2952.5 9674.3 9794.4 9934.2 10123.9 10422.3 1810.3 1904.8 1967.6 1979.3 1986.4 83049.6 83859.9 84168.6 84169.0 84913.0 9186.7 9598.0 9856.9 9871.4 10497.0 12966.3 13036.3 13184.2 13195.3 13213.4 7787.4 7795.7 7899.1 7934.5 7985.0 7935.4 7977.8 8114.7 8160.6 8166.9 3945.9 4077.6 4095.8 4097.4 4208.9 1799.5 1825.6 1952.4 1968.7 1991.3 881.2 976.4 989.2 995.3 1041.2 19769.6 20229.9 20299.2 20364.6 20390.8 954.1 982.9 989.7 1018.6 1032.1 10818.0 11132.7 11136.3 11170.1 11186.1 525813.0 526197.0 531276.0 534285.0 536212.0 83425.0 88758.6 89282.6 91304.5 103189.0 1020.8 1054.4 1056.1 1057.3 1063.1 17653.6 17940.6 18342.7 18689.5 18709.9 43818.6 44024.0 44913.0 44936.6 45124.3 62117.4 62258.4 62511.0 62842.7 64161.0 7661.5 8145.8 8147.3 8271.7 8843.7 7847.9 12212.3 12867.5 13684.2 13787.4 11872.6 11969.5 12175.6 12193.7 12212.6 11399.8 11601.7 11652.5 11672.8 12075.7 11639.8 11989.3 12136.8 12258.1 12266.7 8113.0 8240.6 8320.4 8355.2 8423.1 11361.1 11453.3 11650.8 11763.7 11853.1 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#3] mootools.html Cycles:180 Average:5934.30 Median:4638.63 stddev:3437.59 (74.1%) stddev-sans-first:3429.71 15:35:43 INFO - PROCESS | 29217 | Values: 1315.4 1436.5 1440.0 1536.7 1743.1 8426.2 8888.7 8930.5 9158.3 9161.2 8959.2 9098.4 9099.5 9099.6 9101.8 2360.0 2383.9 2386.0 2387.6 2389.4 2300.4 2344.6 2379.0 2381.8 2384.6 2265.7 2286.7 2304.5 2310.7 2313.4 2273.6 2304.4 2308.2 2309.1 2322.1 2276.5 2284.1 2284.6 2285.7 2296.1 8851.0 8992.9 9153.7 9162.6 9172.7 5077.9 5223.2 5269.9 5270.3 5271.8 3340.0 3347.9 3400.8 3423.2 3428.7 984.3 997.4 998.9 1009.6 1010.5 12489.4 12602.8 12713.0 12722.7 12750.6 4373.5 4512.8 4621.2 4623.2 4654.1 10816.7 10819.8 10826.8 10831.4 10846.5 5290.9 5299.7 5308.7 5316.9 5317.0 4148.3 4200.4 4322.7 4684.5 4896.5 4012.6 4029.8 4132.4 4163.0 4203.9 1219.6 1254.9 1258.3 1266.3 1267.2 969.3 972.8 983.5 1003.2 1003.3 5858.0 5987.3 6196.6 6280.8 6296.5 964.9 995.7 996.1 998.5 1030.2 4248.5 4292.1 4330.7 4415.1 4417.9 11067.5 11462.7 11475.2 11483.7 11484.7 11369.2 11383.7 11472.7 11474.1 11486.7 7795.5 8112.7 8133.7 8327.0 8512.6 5953.9 6175.5 6235.4 6250.1 6394.4 10304.0 10465.5 10478.7 10564.4 10647.0 9574.4 10614.4 10838.5 10847.5 10859.9 7071.8 7721.3 7953.1 8020.9 8217.1 8321.3 8594.0 8607.2 8785.0 8870.0 7812.7 7832.2 7864.7 7886.2 8063.0 7672.3 7726.1 7729.1 7761.9 7764.3 7068.5 7152.6 7176.9 7195.5 7234.0 5295.9 5306.3 5410.3 5436.9 5518.2 5284.6 5365.3 5370.8 5415.5 5480.0 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#4] prototype.html Cycles:180 Average:4533.31 Median:5164.63 stddev:1332.19 (25.8%) stddev-sans-first:1297.37 15:35:43 INFO - PROCESS | 29217 | Values: 293.5 314.8 316.5 317.0 321.7 5117.6 5179.9 5234.7 5235.8 5237.6 5161.8 5167.5 5169.2 5170.9 5242.1 4092.9 4094.7 4095.0 4096.7 4113.1 4063.5 4104.6 4119.2 4130.1 4134.9 3890.3 4026.2 4042.1 4076.3 4098.2 4036.8 4051.3 4073.3 4091.5 4103.1 3592.7 3596.0 3604.7 3606.2 3615.1 4968.0 5024.5 5036.8 5060.6 5063.8 4606.3 4628.4 4637.4 4653.4 4658.0 5345.5 5347.7 5362.0 5364.4 5426.8 3666.6 3846.4 3936.8 3954.9 3962.6 5321.9 5399.2 5574.4 5592.9 5594.6 3905.5 3909.4 3915.7 3937.2 3944.7 3833.1 3981.1 3996.0 3998.4 4000.2 4523.4 4525.7 4538.2 4560.7 4589.2 5354.5 5387.5 5390.9 5399.4 5409.4 1336.5 1352.7 1362.3 1370.8 1375.7 870.9 873.9 884.9 894.0 897.4 4720.5 4735.4 4750.0 4763.2 4820.7 4982.6 4984.0 4984.9 4997.5 5014.7 4646.0 4681.3 4994.3 5309.4 5326.8 2939.3 2946.7 2948.2 2957.0 2969.1 5712.9 5825.9 5832.5 5848.7 5896.2 5628.7 5632.2 5662.5 5679.3 5714.9 5707.0 5766.6 5791.9 5801.3 5812.3 5323.0 5535.4 5576.9 5588.4 5597.7 5292.7 5370.0 5524.1 5551.3 5558.8 5663.8 5758.2 5774.5 5774.8 5794.4 3925.8 3933.6 3948.3 3948.9 3961.3 5374.2 5435.7 5540.9 5603.0 5631.1 5414.9 5427.9 5438.1 5442.3 5444.4 5395.4 5436.1 5452.3 5460.3 5473.2 5254.8 5302.0 5332.5 5342.6 5369.5 5431.4 5461.7 5486.5 5492.2 5493.1 5083.7 5319.3 5341.4 5362.9 5383.4 15:35:43 INFO - PROCESS | 29217 | 15:35:43 INFO - PROCESS | 29217 | [#5] yui.html Cycles:180 Average:21162.92 Median:4694.39 stddev:30826.60 (656.7%) stddev-sans-first:30875.00 15:35:43 INFO - PROCESS | 29217 | Values: 758.8 771.3 775.0 809.3 820.0 15602.0 16326.5 16396.6 16411.5 16436.1 14286.9 14986.6 15091.5 15122.0 15152.1 812.7 829.2 832.4 832.7 833.2 659.4 790.2 816.8 818.1 827.6 852.1 853.7 854.8 855.4 855.8 850.4 853.6 853.6 853.7 854.4 501.3 602.8 613.9 620.6 623.4 14334.8 14375.8 14402.4 14480.6 14487.3 14014.4 14314.3 14768.6 14974.5 15132.6 13462.0 13568.5 13756.1 14036.6 14253.9 4527.8 4671.4 4717.3 4773.2 4801.5 84827.6 84943.0 85757.0 85829.0 85976.0 11617.8 11647.1 11693.5 12093.4 12355.0 21708.8 21718.0 21737.8 21789.2 21840.5 8585.0 8848.7 8887.1 8895.1 8929.6 4874.2 4905.4 4948.5 4954.2 4954.3 3429.4 3555.6 3561.5 3562.8 3564.1 591.5 635.6 635.7 636.8 637.3 594.4 602.6 721.5 741.8 756.4 9378.0 9815.8 9901.7 10063.7 10165.5 625.6 680.6 696.0 704.6 720.2 4188.2 4515.2 4620.0 4654.0 4738.5 65660.7 73664.6 105661.5 126272.0 134164.0 64789.4 66821.7 73896.0 112923.4 117749.4 97870.3 99809.0 100794.5 100861.0 102462.0 8754.3 8768.3 8778.5 8835.3 8873.8 56055.4 56255.0 57284.7 57611.7 58172.9 80921.6 83700.2 86568.6 87223.1 87843.6 11670.6 11880.2 11928.4 12032.6 12387.0 12644.0 13080.5 13139.1 13440.3 14102.4 12962.6 13014.9 13087.3 13357.7 13420.8 12815.7 13225.1 13232.2 13568.8 13611.7 12371.4 12923.4 12944.4 13245.9 13261.2 10233.9 10468.4 10545.5 10832.9 10859.4 10092.2 10603.9 10665.1 10715.1 10751.8 15:35:43 INFO - PROCESS | 29217 | -------- Summary: end -------- 15:35:43 INFO - PROCESS | 29217 | 15:35:44 INFO - TEST-INFO | 29217: exit 0 15:35:44 INFO - TEST-OK | dromaeo_css | took 1116030ms 15:35:44 INFO - TEST-START | kraken 15:35:44 INFO - operating with platform_type : linux_ 15:35:44 INFO - Initialising browser for kraken test... 15:35:44 INFO - TEST-INFO | started process 29404 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvwpi75/profile http://localhost:56141/getInfo.html) 15:35:49 INFO - PROCESS | 29404 | __metrics Screen width/height:1600/1200 15:35:49 INFO - PROCESS | 29404 | colorDepth:24 15:35:49 INFO - PROCESS | 29404 | Browser inner width/height: 1024/697 15:35:49 INFO - PROCESS | 29404 | __metrics 15:35:50 INFO - PROCESS | 29404 | 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" 15:35:50 INFO - PROCESS | 29404 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:35:51 INFO - TEST-INFO | 29404: exit 0 15:35:51 INFO - Browser initialized. 15:35:51 INFO - Running cycle 1/1 for kraken test... 15:35:51 INFO - TEST-INFO | started process 29482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvwpi75/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:03 INFO - PROCESS | 29482 | RSS: Main: 156213248 15:36:03 INFO - PROCESS | 29482 | 15:36:05 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56141/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:06 INFO - PROCESS | 29482 | RSS: Main: 212713472 15:36:06 INFO - PROCESS | 29482 | 15:36:09 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56141/tests/kraken/driver.html?testName=audio-dft) 15:36:09 INFO - PROCESS | 29482 | RSS: Main: 190824448 15:36:09 INFO - PROCESS | 29482 | 15:36:13 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56141/tests/kraken/driver.html?testName=audio-fft) 15:36:13 INFO - PROCESS | 29482 | RSS: Main: 269828096 15:36:13 INFO - PROCESS | 29482 | 15:36:15 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56141/tests/kraken/driver.html?testName=audio-oscillator) 15:36:16 INFO - PROCESS | 29482 | RSS: Main: 179499008 15:36:16 INFO - PROCESS | 29482 | 15:36:18 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56141/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:19 INFO - PROCESS | 29482 | RSS: Main: 183799808 15:36:19 INFO - PROCESS | 29482 | 15:36:22 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56141/tests/kraken/driver.html?testName=imaging-darkroom) 15:36:22 INFO - PROCESS | 29482 | RSS: Main: 218787840 15:36:22 INFO - PROCESS | 29482 | 15:36:26 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56141/tests/kraken/driver.html?testName=imaging-desaturate) 15:36:26 INFO - PROCESS | 29482 | RSS: Main: 219783168 15:36:26 INFO - PROCESS | 29482 | 15:36:29 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56141/tests/kraken/driver.html?testName=json-parse-financial) 15:36:30 INFO - PROCESS | 29482 | RSS: Main: 218390528 15:36:30 INFO - PROCESS | 29482 | 15:36:31 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56141/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:36:32 INFO - PROCESS | 29482 | RSS: Main: 183726080 15:36:32 INFO - PROCESS | 29482 | 15:36:33 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:36:34 INFO - PROCESS | 29482 | RSS: Main: 188219392 15:36:34 INFO - PROCESS | 29482 | 15:36:36 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:36:37 INFO - PROCESS | 29482 | RSS: Main: 193540096 15:36:37 INFO - PROCESS | 29482 | 15:36:39 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:36:40 INFO - PROCESS | 29482 | RSS: Main: 188805120 15:36:40 INFO - PROCESS | 29482 | 15:36:43 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:36:43 INFO - PROCESS | 29482 | RSS: Main: 192135168 15:36:43 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | Cycle 1(1): loaded http://localhost:56141/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56141/tests/kraken/driver.html?testName=ai-astar) 15:36:45 INFO - PROCESS | 29482 | RSS: Main: 243462144 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | __start_tp_report 15:36:45 INFO - PROCESS | 29482 | _x_x_mozilla_page_load 15:36:45 INFO - PROCESS | 29482 | _x_x_mozilla_page_load_details 15:36:45 INFO - PROCESS | 29482 | |i|pagename|runs| 15:36:45 INFO - PROCESS | 29482 | |0;ai-astar;101;110;111;112;110;109;109;109;109;110 15:36:45 INFO - PROCESS | 29482 | |1;audio-beat-detection;143;115;121;174;118;118;145;118;152;121 15:36:45 INFO - PROCESS | 29482 | |2;audio-dft;161;161;162;161;162;162;162;161;162;161 15:36:45 INFO - PROCESS | 29482 | |3;audio-fft;75;76;73;117;76;76;74;74;100;75 15:36:45 INFO - PROCESS | 29482 | |4;audio-oscillator;83;84;115;85;83;84;120;83;84;125 15:36:45 INFO - PROCESS | 29482 | |5;imaging-gaussian-blur;118;117;116;117;117;117;117;116;117;117 15:36:45 INFO - PROCESS | 29482 | |6;imaging-darkroom;146;147;146;146;146;147;146;146;146;147 15:36:45 INFO - PROCESS | 29482 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;96;96 15:36:45 INFO - PROCESS | 29482 | |8;json-parse-financial;52;52;52;51;52;52;52;52;52;51 15:36:45 INFO - PROCESS | 29482 | |9;json-stringify-tinderbox;61;62;61;61;62;61;61;61;63;62 15:36:45 INFO - PROCESS | 29482 | |10;stanford-crypto-aes;77;76;75;75;75;75;76;75;75;79 15:36:45 INFO - PROCESS | 29482 | |11;stanford-crypto-ccm;125;121;120;123;124;117;122;129;160;137 15:36:45 INFO - PROCESS | 29482 | |12;stanford-crypto-pbkdf2;160;156;160;161;160;164;164;158;160;160 15:36:45 INFO - PROCESS | 29482 | |13;stanford-crypto-sha256-iterative;63;64;64;63;63;62;62;62;61;66 15:36:45 INFO - PROCESS | 29482 | __end_tp_report 15:36:45 INFO - PROCESS | 29482 | __start_cc_report 15:36:45 INFO - PROCESS | 29482 | _x_x_mozilla_cycle_collect,1691 15:36:45 INFO - PROCESS | 29482 | __end_cc_report 15:36:45 INFO - PROCESS | 29482 | __startTimestamp1470004605589__endTimestamp 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | ------- Summary: start ------- 15:36:45 INFO - PROCESS | 29482 | Number of tests: 14 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:2.98 (2.7%) stddev-sans-first:1.05 15:36:45 INFO - PROCESS | 29482 | Values: 101.0 110.0 111.0 112.0 110.0 109.0 109.0 109.0 109.0 110.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#1] audio-beat-detection Cycles:10 Average:132.50 Median:132.00 stddev:19.92 (15.1%) stddev-sans-first:20.76 15:36:45 INFO - PROCESS | 29482 | Values: 143.0 115.0 121.0 174.0 118.0 118.0 145.0 118.0 152.0 121.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 15:36:45 INFO - PROCESS | 29482 | Values: 161.0 161.0 162.0 161.0 162.0 162.0 162.0 161.0 162.0 161.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:14.77 (19.7%) stddev-sans-first:15.47 15:36:45 INFO - PROCESS | 29482 | Values: 75.0 76.0 73.0 117.0 76.0 76.0 74.0 74.0 100.0 75.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:83.50 stddev:17.70 (21.2%) stddev-sans-first:18.27 15:36:45 INFO - PROCESS | 29482 | Values: 83.0 84.0 115.0 85.0 83.0 84.0 120.0 83.0 84.0 125.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 15:36:45 INFO - PROCESS | 29482 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 15:36:45 INFO - PROCESS | 29482 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:36:45 INFO - PROCESS | 29482 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 15:36:45 INFO - PROCESS | 29482 | Values: 52.0 52.0 52.0 51.0 52.0 52.0 52.0 52.0 52.0 51.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 15:36:45 INFO - PROCESS | 29482 | Values: 61.0 62.0 61.0 61.0 62.0 61.0 61.0 61.0 63.0 62.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#10] stanford-crypto-aes Cycles:10 Average:75.80 Median:75.50 stddev:1.32 (1.7%) stddev-sans-first:1.32 15:36:45 INFO - PROCESS | 29482 | Values: 77.0 76.0 75.0 75.0 75.0 75.0 76.0 75.0 75.0 79.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:124.50 stddev:12.59 (10.1%) stddev-sans-first:13.31 15:36:45 INFO - PROCESS | 29482 | Values: 125.0 121.0 120.0 123.0 124.0 117.0 122.0 129.0 160.0 137.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:160.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 15:36:45 INFO - PROCESS | 29482 | Values: 160.0 156.0 160.0 161.0 160.0 164.0 164.0 158.0 160.0 160.0 15:36:45 INFO - PROCESS | 29482 | 15:36:45 INFO - PROCESS | 29482 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.41 (2.2%) stddev-sans-first:1.50 15:36:45 INFO - PROCESS | 29482 | Values: 63.0 64.0 64.0 63.0 63.0 62.0 62.0 62.0 61.0 66.0 15:36:45 INFO - PROCESS | 29482 | -------- Summary: end -------- 15:36:45 INFO - PROCESS | 29482 | 15:36:46 INFO - TEST-INFO | 29482: exit 0 15:36:46 INFO - TEST-OK | kraken | took 62072ms 15:36:46 INFO - SUITE-END | took 1178s 15:36:46 INFO - Completed test suite (00:19:38) 15:36:46 INFO - javascript benchmark 15:36:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2993.3713920076784, 3037.5595538646885, 3044.147638661175, 3063.5711000459924, 3071.355015446756, 5211.687298762067, 5281.181416880385, 5290.365156181254, 5357.946420535798, 5387.919181212285, 5262.158054711247, 5321.813736519222, 5336.706481143536, 5364.275822763926, 5369.704666243355, 4800.039992001605, 4840.128776820171, 4865.975670121645, 4880.243562247857, 4962.478939711333, 4886.0, 4904.092742842586, 4933.555979961803, 4948.628852836052, 4963.057019166365, 4768.523147685239, 4771.212749896263, 4784.354112194844, 4806.206975848981, 4907.067657145149, 4797.496262892416, 4798.352222449959, 4822.42130944289, 4828.637852161067, 4921.163402221595, 4825.710457372569, 4841.419029716457, 4844.2733589961435, 4863.465018847924, 4884.462709101996, 5108.2848401223855, 5220.582353411718, 5280.260763493114, 5303.814366497154, 5394.02907476654, 5657.377688454267, 5659.320881494205, 5664.631798933097, 5666.0, 5700.800471983503, 5738.598298119134, 5749.712514374264, 5750.223719797816, 5755.798547050833, 5766.942330576682, 5179.249008893689, 5264.789408423697, 5312.468753124656, 5314.229436731653, 5381.919271210857, 5908.0, 5917.260342457193, 5918.911216331759, 5920.348761636216, 5920.851978700567, 5654.349749778746, 5660.462256085665, 5664.348599910981, 5665.716714164275, 5666.433356664383, 5532.5573954083575, 5602.551795856321, 5617.747201375948, 5641.210230567724, 5730.627509211888, 5611.831645050655, 5615.382307946123, 5616.9157462638095, 5679.148127780783, 5687.7156142192725, 5598.412166722435, 5603.691796951125, 5644.125160600113, 5653.604247702704, 5656.91514627281, 4272.230998420314, 4280.186764514732, 4281.357796330575, 4297.613214810682, 4396.3625274335045, 3848.3842585186794, 3857.0550215197454, 3877.205172939597, 3884.669803066771, 3904.06302487401, 5362.651427657268, 5364.168553874156, 5366.168243922199, 5370.060239458157, 5403.756830942617, 5509.669419834822, 5617.606767526234, 5638.182463542763, 5650.434956504317, 5682.516986056149, 5396.028714831289, 5406.567474602022, 5415.972920135374, 5440.91838622421, 5446.0, 4956.10790057793, 4963.875903102451, 4963.925541116886, 4968.850934471971, 5000.574951129122, 5678.034734095214, 5815.82552523425, 5906.084556893774, 5911.965406053923, 5928.140419639129, 5706.115552089433, 5712.400197979152, 5719.599628025998, 5757.337906140848, 5768.134779783066, 5897.469227769521, 5897.852553686193, 5899.46904778572, 5907.5273978080795, 5942.435468630473, 5530.0, 5541.445855414427, 5546.306711661043, 5552.222688823649, 5579.8884022320135, 5678.943210567842, 5721.456461636138, 5727.8568035799435, 5754.53963682896, 5778.16216648592, 5523.4200408956485, 5558.833235002957, 5564.10974244119, 5576.1356989666665, 5586.16207568868, 5428.402875683592, 5506.366767821752, 5515.641483303572, 5568.16477528374, 5579.4420557945505, 5494.587905907153, 5497.862553436196, 5501.037318469332, 5536.474034966672, 5552.0, 5594.468525490072, 5596.796688711946, 5632.633878797865, 5633.746481408429, 5649.0, 5309.150535914235, 5326.786928522815, 5379.381371142369, 5469.097598896137, 5469.288992430958], "value": 5242.451187585703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.575888998256, 623.8409205652558, 644.1561554363784, 649.7043845050503, 663.0352836622715, 33477.33045339095, 44612.33081503776, 45362.0, 45461.545384546145, 45524.31713524291, 46655.53344466554, 47005.529944700545, 47054.529454705444, 47071.11715531375, 47102.52897471024, 8860.911390886089, 9006.0, 9006.234470070052, 9010.279177665803, 9012.27901767857, 8634.136586341347, 8738.737837864874, 8739.912600874006, 8741.868871966926, 8743.03826579079, 8749.431286966326, 8784.07767184446, 8788.64845406183, 8888.466691998501, 8892.555372231394, 8821.955890220572, 8850.026497085348, 8854.513001784899, 8868.29053675711, 8874.511901845395, 8836.0, 8909.821803563893, 8910.955445222797, 8918.598663060178, 8926.866097008486, 43700.0, 44056.339154912705, 44072.0, 44079.11841763179, 44187.55812441867, 3505.1938054247667, 3659.0852286928266, 3785.1483416231404, 3913.941290880639, 4119.588041195856, 30303.0, 30782.53826192609, 30934.53598196029, 30977.91577294784, 34192.48711269333, 3920.4511368408444, 4064.6138616831354, 4069.8982525436804, 4084.0402505411503, 4168.91662166758, 351399.0, 351667.0, 354762.0, 355250.0, 356176.0, 54592.45407545953, 54723.45276547263, 54781.72609136929, 54839.72580137074, 54941.72529137409, 61700.69149654313, 62098.689506552175, 62157.37842621606, 62224.68887655623, 62274.68862655658, 17043.573910652332, 17271.91364043197, 17274.58155858181, 17284.22221000058, 17293.740593891103, 13351.732965340832, 13422.731545369037, 13445.932770336085, 13451.991100667294, 13456.663583410298, 16808.66382672364, 17651.117444127998, 17681.469555913343, 17698.469045928643, 17744.91127544354, 6612.603243805387, 6675.7997260082275, 6730.663466826639, 6733.259341472434, 6777.220619628707, 954.7183580843667, 1049.2917280835406, 1067.530286673861, 1075.8816530181673, 1121.0919155484085, 32816.0, 32934.03593676581, 33266.66733332635, 33360.0, 33670.8316458421, 1059.8463222832645, 1065.658989123473, 1092.2408925796653, 1110.4558766204664, 1161.9825702614298, 16753.225330780668, 17405.65188696219, 17410.21654025572, 17562.0, 17726.20232089559, 546678.0, 550972.0, 551087.0, 551663.0, 553506.0, 60730.69634651887, 60879.0, 61073.0, 61145.38854611486, 61199.16402507932, 1279.174932168746, 1356.3828458051562, 1363.7340718559785, 1368.7125703602355, 1369.5069774880835, 31418.37163256768, 32026.519602206452, 32875.0, 33711.32577348488, 34633.30733385268, 58968.115478267006, 59414.0, 59438.40561594329, 59580.70209648924, 59589.70205148947, 477199.0, 479201.0, 481874.0, 482484.0, 483573.0, 28528.0, 28650.57024144681, 28977.840886365153, 29042.564361534172, 29090.7090929088, 50062.499375005784, 50601.0, 50855.74572127116, 51518.484815151365, 51960.74019629878, 42755.14489710251, 44476.332855006556, 44497.55502444934, 44665.77667111644, 45289.773551132035, 42875.78562107169, 43130.56869431266, 43579.0, 44466.77766611146, 44643.77678111719, 36265.637343627284, 37205.81397092997, 38971.0, 43557.0, 43942.780286098365, 34445.82777086098, 38417.80791096027, 38579.80710096432, 39369.0, 39733.40399893946, 28862.567061494516, 29302.70697293, 29345.85327073351, 29537.2615684601, 29795.851020745624], "value": 20534.66663332719, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.1532475306794, 386.3798603241797, 387.9224155168966, 401.6846775281401, 404.89472737088346, 18674.253029878804, 19977.500562485944, 20031.298904538344, 20035.799642003574, 20091.59816803665, 18427.0, 18718.34485792994, 18976.715349269736, 19147.808521914776, 19215.423537293904, 5592.636478628876, 5721.942780572203, 5869.354371019184, 5907.43879331465, 5934.703264836762, 5915.438033386843, 5942.792002279921, 5953.107033944922, 5953.136795164699, 5955.553333499983, 5913.2608423947, 5946.375630558794, 6543.0, 6597.274299827015, 6672.499562532806, 6016.0, 6652.467802575807, 6664.633445160514, 6699.933000669979, 6706.061151438778, 5728.713564321767, 5776.191333213354, 5780.364159942403, 5784.971075144639, 5785.537157027427, 13640.727185456235, 13855.792163117561, 21698.56602867934, 22394.32817015492, 22464.550708985727, 2262.7850354216325, 2269.1490690990877, 2534.2650631316897, 2854.957175642367, 2952.46855565998, 9674.322797404184, 9794.363366381162, 9934.205263578897, 10123.949380253052, 10422.322549034365, 1810.3301778341965, 1904.8475672218246, 1967.5572996075912, 1979.3171355882341, 1986.4040787763613, 83049.58475207585, 83859.87191088765, 84168.57915710383, 84169.0, 84913.0, 9186.724398267928, 9597.952010240044, 9856.901430985741, 9871.358361706467, 10497.0, 12966.286854222919, 13036.348182590833, 13184.208947463183, 13195.340232988505, 13213.405396757169, 7787.3770098392, 7795.688172473151, 7899.09160446556, 7934.523928564305, 7985.0, 7935.444518883623, 7977.840443191104, 8114.675412983414, 8160.551169685606, 8166.877496837552, 3945.881623551298, 4077.5922407758985, 4095.7542547447247, 4097.426360309559, 4208.894777630584, 1799.4601619514094, 1825.5709908171568, 1952.3947576251408, 1968.6653268944028, 1991.32295019694, 881.1628952495155, 976.3653625143571, 989.2036910287288, 995.2585323933715, 1041.2190856857358, 19769.60460790776, 20229.898850505655, 20299.188032478833, 20364.592708145756, 20390.79609203889, 954.1317401164906, 982.9115379615725, 989.6734077754323, 1018.6231094494937, 1032.0659802878406, 10818.0, 11132.721681957853, 11136.33182009082, 11170.050545703543, 11186.105111591216, 525813.0, 526197.0, 531276.0, 534285.0, 536212.0, 83425.0, 88758.55620721856, 89282.55358723295, 91304.54347728219, 103189.0, 1020.7601213714771, 1054.409530662816, 1056.0917610854658, 1057.3338796558119, 1063.0538820449806, 17653.646927061644, 17940.641187175923, 18342.724859126858, 18689.53276168107, 18709.90645046766, 43818.56181438272, 44024.0, 44913.0, 44936.550634494546, 45124.32313515365, 62117.37882621116, 62258.37741622707, 62511.0, 62842.68578657077, 64161.0, 7661.463697541119, 8145.7963550909335, 8147.266745992652, 8271.710490132817, 8843.734687959372, 7847.921520784719, 12212.328321942023, 12867.549635762703, 13684.247366394944, 13787.448502059806, 11872.584459543874, 11969.521219151136, 12175.573854915036, 12193.69515762113, 12212.633620991384, 11399.829002565135, 11601.709957251136, 11652.533898644298, 11672.824907626222, 12075.73204813517, 11639.825402618799, 11989.280643161439, 12136.817947730615, 12258.141930065167, 12266.693332666755, 8113.0, 8240.587970601566, 8320.37597180226, 8355.248027677539, 8423.073461919306, 11361.147913906656, 11453.312801231952, 11650.825237621273, 11763.705907352385, 11853.051755859684], "value": 9229.046883306075, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.4080663701336, 1436.4972259709102, 1439.9712005759884, 1536.661934374438, 1743.058748275931, 8426.15738426157, 8888.688895888645, 8930.508822014795, 9158.267338612912, 9161.221296189815, 8959.238464730506, 9098.363114581982, 9099.499527525983, 9099.59051842667, 9101.772455688611, 2359.964600530989, 2383.9284821455385, 2386.0, 2387.582173119697, 2389.4026493376655, 2300.424893776556, 2344.648302754592, 2379.0245999140357, 2381.7975472084895, 2384.582698027847, 2265.6714776357417, 2286.702728645274, 2304.5045315257216, 2310.734265559457, 2313.4447732544236, 2273.5566564519927, 2304.3893368257427, 2308.203669733939, 2309.1340747219792, 2322.059565875823, 2276.5105502316915, 2284.0749496453964, 2284.6344584866506, 2285.714285714286, 2296.127471560813, 8851.0, 8992.865107023334, 9153.679621213225, 9162.587683554257, 9172.6789562365, 5077.949220507821, 5223.216517522402, 5269.947300526984, 5270.341207349082, 5271.762770675329, 3340.0146956647845, 3347.9497807532907, 3400.778949368308, 3423.1613254752497, 3428.668505416316, 984.3454103021483, 997.4015590645627, 998.8751406074241, 1009.6466236817129, 1010.5048526222172, 12489.375531223493, 12602.81095783564, 12713.0, 12722.745545089047, 12750.553730619476, 4373.453318335208, 4512.774361281989, 4621.214393553104, 4623.190941585209, 4654.069186162781, 10816.729581760519, 10819.83770243447, 10826.837597436044, 10831.350118992885, 10846.457677116112, 5290.894182116336, 5299.682019078868, 5308.681479111266, 5316.946830531722, 5316.973415132899, 4148.274052040881, 4200.390943313202, 4322.697411181187, 4684.531546845288, 4896.485868983706, 4012.61880121388, 4029.7783621901076, 4132.40080188371, 4162.979185104055, 4203.936940945889, 1219.5975328141692, 1254.9184303020274, 1258.3205069262679, 1266.3288457117742, 1267.1700036476122, 969.2682025071026, 972.7616733900239, 983.4640121133856, 1003.1623594298696, 1003.2926786615419, 5858.0, 5987.341392446828, 6196.597221180699, 6280.780172694025, 6296.464800492009, 964.8600952861937, 995.6564985080136, 996.0736515041082, 998.495759641391, 1030.2427715629008, 4248.532661407243, 4292.12011537634, 4330.7401555906745, 4415.116976604692, 4417.889552761142, 11067.50196241155, 11462.713432164095, 11475.19673622855, 11483.655490335303, 11484.655460336204, 11369.204155709187, 11383.658490245305, 11472.713182170513, 11474.082073434105, 11486.712832179095, 7795.4543181976715, 8112.675492980215, 8133.7153199638915, 8327.042390125092, 8512.574371281411, 5953.910691339547, 6175.475084617858, 6235.407636274546, 6250.124982502362, 6394.392532709385, 10304.021117993778, 10465.529051192563, 10478.738031549272, 10564.418956957134, 10647.041766241075, 9574.377665451724, 10614.416207108683, 10838.458077096271, 10847.511861966073, 10859.891401085888, 7071.82320441993, 7721.305082542598, 7953.125156232694, 8020.879686804819, 8217.096119426978, 8321.334293256647, 8593.95703021481, 8607.225349718556, 8785.0, 8870.0, 7812.687492500237, 7832.216778322122, 7864.72473463426, 7886.211378862068, 8063.0, 7672.309492145734, 7726.1114971776715, 7729.149793522821, 7761.92238077612, 7764.301212890641, 7068.505204635626, 7152.642367881481, 7176.928230717626, 7195.46034047459, 7233.963830180815, 5295.947040529545, 5306.28365170697, 5410.323709536308, 5436.8640783979135, 5518.199861020049, 5284.550813180923, 5365.302510673588, 5370.812021579226, 5415.48552887475, 5480.0], "value": 4705.618373342539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.5098385695889, 314.7890913088231, 316.52837272464023, 316.96988786065344, 321.6622546326357, 5117.592662017947, 5179.896402071956, 5234.659747116435, 5235.795766973599, 5237.554807841338, 5161.754636733014, 5167.509086636764, 5169.224616307556, 5170.870728231796, 5242.108841496945, 4092.877213683579, 4094.7338423002484, 4095.0, 4096.672266218696, 4113.095119073799, 4063.4514340564087, 4104.630583247507, 4119.237940980913, 4130.112025914428, 4134.896627584304, 3890.2997460457104, 4026.2148880968225, 4042.110735638155, 4076.266272071026, 4098.180363927211, 4036.8183431745642, 4051.270771261172, 4073.307537718595, 4091.5499295077516, 4103.148311923214, 3592.6946209572216, 3596.029072150505, 3604.711623070148, 3606.242689035306, 3615.1142969972525, 4967.975160124176, 5024.497550244946, 5036.84889453317, 5060.645754797166, 5063.8480845574695, 4606.30905364198, 4628.4445866496235, 4637.397138372024, 4653.440607665804, 4657.976710116462, 5345.465453454663, 5347.732613369316, 5362.0, 5364.436734142898, 5426.782928682848, 3666.633336666339, 3846.442265871461, 3936.8031598420253, 3954.8813535593977, 3962.5641179470235, 5321.946780532223, 5399.217113518518, 5574.386817450078, 5592.944070559323, 5594.552435805126, 3905.453236546886, 3909.4331321958725, 3915.686745060388, 3937.2322397132284, 3944.74359166659, 3833.1375440525658, 3981.1042515433796, 3996.0219691252437, 3998.4202290668272, 4000.19996000801, 4523.434570678665, 4525.705829121097, 4538.205813982539, 4560.703554268962, 4589.173948689201, 5354.518093371616, 5387.488188622075, 5390.8921821563345, 5399.352077750695, 5409.404965453797, 1336.4988129451456, 1352.6888815572277, 1362.284800479756, 1370.8347904281281, 1375.7111006688508, 870.9433886797337, 873.8683404990552, 884.9469031857981, 893.9687110951086, 897.3763234552055, 4720.465848599219, 4735.360726301905, 4750.049990002013, 4763.21379482694, 4820.686070686091, 4982.57996471004, 4983.950160498349, 4984.900301994012, 4997.525235102659, 5014.674046186986, 4646.024334889644, 4681.321208424827, 4994.300797888299, 5309.36287645485, 5326.777299733682, 2939.2798764302884, 2946.7347938685193, 2948.248196709825, 2956.985215073954, 2969.109267219869, 5712.914306285409, 5825.912611310834, 5832.533397328203, 5848.695487049803, 5896.174535565025, 5628.718564071862, 5632.183908045977, 5662.518685911661, 5679.261695979496, 5714.885702285931, 5706.9714651426475, 5766.625169363978, 5791.8552036199435, 5801.274840644919, 5812.3025236971835, 5323.0, 5535.4187810280955, 5576.91634625489, 5588.38527761954, 5597.6641401516035, 5292.7353632319, 5369.973150134224, 5524.14375771756, 5551.278333816578, 5558.805441809517, 5663.801766938137, 5758.193852860519, 5774.48029677331, 5774.82675519735, 5794.449527294901, 3925.7644541327613, 3933.6066393360434, 3948.3485224937563, 3948.874570747323, 3961.3265744822875, 5374.220737993047, 5435.701036443033, 5540.889182216414, 5602.9719851402115, 5631.071842641885, 5414.918776218281, 5427.918581221364, 5438.1238472726845, 5442.292501974718, 5444.401115877329, 5395.4083545355, 5436.0758671026815, 5452.26394436746, 5460.26286451324, 5473.2063850742215, 5254.816081437131, 5301.973490132525, 5332.493413125747, 5342.610921160449, 5369.4630536946, 5431.402545720047, 5461.699606521679, 5486.478784515464, 5492.203630473638, 5493.093639549589, 5083.694978301313, 5319.281896943863, 5341.385740639721, 5362.946370536245, 5383.411893491351], "value": 4103.018780200479, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.8330567275201, 771.2634434115419, 774.9651265693043, 809.2999555384587, 819.9713010044646, 15601.985870918397, 16326.510204693863, 16396.590085247844, 16411.507654770347, 16436.096014719187, 14286.928565357159, 14986.550403487912, 15091.54725358241, 15122.017068890515, 15152.090874547508, 812.6546217857431, 829.2122483640535, 832.4422636833306, 832.7376876283981, 833.1543483364389, 659.3867703036175, 790.1584812175015, 816.8202995341045, 818.1409520003972, 827.6192951242435, 852.1393392673414, 853.6798700487317, 854.786303424144, 855.3627547477138, 855.7860534866284, 850.4429598612857, 853.5518852602371, 853.6158728572167, 853.7268074216254, 854.406187699549, 501.323074316411, 602.8251806975971, 613.9355367686418, 620.5842085802523, 623.3548277532732, 14334.784978225334, 14375.784363234456, 14402.351894164683, 14480.565583032527, 14487.348069336906, 14014.439422423193, 14314.284285785669, 14768.630784230372, 14974.47589334368, 15132.621684457867, 13462.0, 13568.525101621395, 13756.105853119516, 14036.578902632835, 14253.857461425358, 4527.750973696446, 4671.439427268716, 4717.339572459859, 4773.158307599104, 4801.471838097841, 84827.5758621203, 84942.99999999939, 85757.0, 85829.0, 85976.0, 11617.76764464706, 11647.12646551512, 11693.532258709556, 12093.395330233629, 12355.0, 21708.78291217099, 21718.0, 21737.78262217389, 21789.23737669174, 21840.453988650093, 8585.0, 8848.690295839615, 8887.111288871061, 8895.110488951183, 8929.553522323988, 4874.195757699939, 4905.386826646669, 4948.505149485023, 4954.207326827763, 4954.281629163751, 3429.3655673700446, 3555.591107022674, 3561.519194908706, 3562.750607457505, 3564.108972756811, 591.4913174669781, 635.6154526511486, 635.7171058878847, 636.7994081864198, 637.3499030988459, 594.3759052994338, 602.5962605054624, 721.5236356057549, 741.7774667599698, 756.4364548411359, 9378.0, 9815.803683926282, 9901.653442129635, 10063.698089057194, 10165.51867982349, 625.6308777821016, 680.5950459476603, 695.9838635592092, 704.5561296383246, 720.2293545905831, 4188.204241194163, 4515.164694531522, 4620.0, 4653.95346046542, 4738.526147385234, 65660.6716966412, 73664.63167684128, 105661.47169264105, 126272.0, 134164.0, 64789.35210647927, 66821.66589167023, 73896.0, 112923.4353828242, 117749.41125294319, 97870.27686400864, 99809.0, 100794.49602751939, 100860.99139008662, 102462.0, 8754.292912882182, 8768.34237432208, 8778.517181555077, 8835.33734969867, 8873.778155545904, 56055.43944560502, 56255.0, 57284.71357643352, 57611.71194144002, 58172.92789504694, 80921.59539202266, 83700.2334894919, 86568.56715716633, 87223.12776872404, 87843.56078219568, 11670.649880503599, 11880.227785193934, 11928.4035798208, 12032.578859740217, 12387.0, 12644.0, 13080.502675127535, 13139.14595551286, 13440.316968885296, 14102.36539355752, 12962.611121666365, 13014.934925325313, 13087.345632718136, 13357.732845343233, 13420.798688019882, 12815.743685126432, 13225.140365876186, 13232.206067635972, 13568.796468053184, 13611.659708507366, 12371.443285052374, 12923.418446169757, 12944.352782360656, 13245.86754132485, 13261.204327740366, 10233.897661023295, 10468.371897686164, 10545.525451354553, 10832.891671083189, 10859.402732849769, 10092.24308176875, 10603.94698026505, 10665.146788257076, 10715.142788577052, 10751.838722419014], "value": 7597.08733740133, "unit": "score"}], "name": "dromaeo_css", "value": 7254.356521092185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 111.0, 112.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 115.0, 121.0, 174.0, 118.0, 118.0, 145.0, 118.0, 152.0, 121.0], "value": 132.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 162.0, 161.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 73.0, 117.0, 76.0, 76.0, 74.0, 74.0, 100.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 115.0, 85.0, 83.0, 84.0, 120.0, 83.0, 84.0, 125.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 52.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 63.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 75.0, 75.0, 75.0, 76.0, 75.0, 75.0, 79.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 120.0, 123.0, 124.0, 117.0, 122.0, 129.0, 160.0, 137.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 156.0, 160.0, 161.0, 160.0, 164.0, 164.0, 158.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 63.0, 63.0, 62.0, 62.0, 62.0, 61.0, 66.0], "value": 63.0, "unit": "score"}], "name": "kraken", "value": 1477.7, "alertThreshold": 2.0}]} 15:36:46 INFO - Return code: 0 15:36:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:36:46 INFO - # TBPL SUCCESS # 15:36:46 INFO - Running post-action listener: _resource_record_post_action 15:36:46 INFO - [mozharness: 2016-07-31 22:36:46.899450Z] Finished run-tests step (success) 15:36:46 INFO - Running post-run listener: _resource_record_post_run 15:36:46 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 295649280; Read time: 136; Write time: 737088 15:36:46 INFO - TinderboxPrint: CPU usage
13.1% 15:36:46 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 136 15:36:46 INFO - TinderboxPrint: I/O write bytes / time
295,649,280 / 737,088 15:36:46 INFO - TinderboxPrint: CPU idle
8,288.4 (86.9%) 15:36:46 INFO - TinderboxPrint: CPU user
1,196.4 (12.5%) 15:36:46 INFO - TinderboxPrint: Swap in / out
0 / 0 15:36:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 6021120; Read time: 0; Write time: 8304 15:36:46 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289087488; Read time: 128; Write time: 728712 15:36:47 INFO - Running post-run listener: _upload_blobber_files 15:36:47 INFO - Blob upload gear active. 15:36:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:36:47 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 15:36:47 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:36:47 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:36:48 INFO - (blobuploader) - INFO - Open directory for files ... 15:36:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:36:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:51 INFO - (blobuploader) - INFO - Done attempting. 15:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:36:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:52 INFO - (blobuploader) - INFO - Done attempting. 15:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:53 INFO - (blobuploader) - INFO - Done attempting. 15:36:53 INFO - (blobuploader) - INFO - Iteration through files over. 15:36:53 INFO - Return code: 0 15:36:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:36:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:36:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7601c2a8184335215abb9546d4e2b57b7f1a79a46b75a138d85872c70492c3a6c9bb6f1b19a0c8132a94df5f502bfeeadd393b6a887c36569fd143b01d2c4733", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01020321f91b31c4c24278afe63ba0d4b352dc751079d8c990bacd225f416e5ac7dbf86a91e09db671587aac7cbd77ba159d07e9d353a74d499bb335ad8b858f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbe95c0008160ff0fbe42d0249334bc62b8280b1f6887f2e2e5998915b85e4051b32191e1095595ed5a7589cfc9b88ab5ed939472aa5ac85cb1e5bac095bdcd9"} 15:36:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:36:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:36:53 INFO - Contents: 15:36:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7601c2a8184335215abb9546d4e2b57b7f1a79a46b75a138d85872c70492c3a6c9bb6f1b19a0c8132a94df5f502bfeeadd393b6a887c36569fd143b01d2c4733", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01020321f91b31c4c24278afe63ba0d4b352dc751079d8c990bacd225f416e5ac7dbf86a91e09db671587aac7cbd77ba159d07e9d353a74d499bb335ad8b858f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbe95c0008160ff0fbe42d0249334bc62b8280b1f6887f2e2e5998915b85e4051b32191e1095595ed5a7589cfc9b88ab5ed939472aa5ac85cb1e5bac095bdcd9"} 15:36:53 INFO - Running post-run listener: copy_logs_to_upload_dir 15:36:53 INFO - Copying logs to upload dir... 15:36:53 INFO - mkdir: /builds/slave/test/build/upload/logs 15:36:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.019254 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-07-31 15:36:52.890390) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 15:36:52.893638) ========= 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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7601c2a8184335215abb9546d4e2b57b7f1a79a46b75a138d85872c70492c3a6c9bb6f1b19a0c8132a94df5f502bfeeadd393b6a887c36569fd143b01d2c4733", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01020321f91b31c4c24278afe63ba0d4b352dc751079d8c990bacd225f416e5ac7dbf86a91e09db671587aac7cbd77ba159d07e9d353a74d499bb335ad8b858f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbe95c0008160ff0fbe42d0249334bc62b8280b1f6887f2e2e5998915b85e4051b32191e1095595ed5a7589cfc9b88ab5ed939472aa5ac85cb1e5bac095bdcd9"} build_url:https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/WvYYCjK6R0G3eYmLbYFreA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7601c2a8184335215abb9546d4e2b57b7f1a79a46b75a138d85872c70492c3a6c9bb6f1b19a0c8132a94df5f502bfeeadd393b6a887c36569fd143b01d2c4733", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01020321f91b31c4c24278afe63ba0d4b352dc751079d8c990bacd225f416e5ac7dbf86a91e09db671587aac7cbd77ba159d07e9d353a74d499bb335ad8b858f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fbe95c0008160ff0fbe42d0249334bc62b8280b1f6887f2e2e5998915b85e4051b32191e1095595ed5a7589cfc9b88ab5ed939472aa5ac85cb1e5bac095bdcd9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-31 15:36:52.918508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:36:52.918807) ========= 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=df17445c3590ff7897617a0e00000225-1469998068.434188-1517851176 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004429 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-31 15:36:53.004033) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 15:36:53.004351) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-31 15:36:53.004645) ========= ========= Total master_lag: 0.18 =========