builder: larch_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1464731912.8 results: success (0) buildid: 20160531123017 builduid: 42c1045af760481394f517dee40f15b0 revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.804639) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.805465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.806262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.821828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.822141) ========= 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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-31 14:58:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 209M=0s 2016-05-31 14:58:33 (209 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120884 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.961079) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:32.961844) ========= 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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016646 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.013765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.014340) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 23a96762bc11fdbd26f032d3fcbcbf7417797acb --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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-31 14:58:33,335 truncating revision to first 12 chars 2016-05-31 14:58:33,335 Setting DEBUG logging. 2016-05-31 14:58:33,335 attempt 1/10 2016-05-31 14:58:33,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/23a96762bc11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 14:58:33,654 unpacking tar archive at: larch-23a96762bc11/testing/mozharness/ program finished with exit code 0 elapsedTime=0.709102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.741702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.742190) ========= script_repo_revision: 23a96762bc11fdbd26f032d3fcbcbf7417797acb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.742843) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.743266) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 14:58:33.755279) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-31 14:58:33.755688) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:58:34 INFO - MultiFileLogger online at 20160531 14:58:34 in /builds/slave/test 14:58:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:58:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:58:34 INFO - {'append_to_log': False, 14:58:34 INFO - 'base_work_dir': '/builds/slave/test', 14:58:34 INFO - 'blob_upload_branch': 'Larch', 14:58:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:58:34 INFO - 'branch': 'Larch', 14:58:34 INFO - 'buildbot_json_path': 'buildprops.json', 14:58:34 INFO - 'config_files': ('talos/linux_config.py',), 14:58:34 INFO - 'default_actions': ('clobber', 14:58:34 INFO - 'read-buildbot-config', 14:58:34 INFO - 'download-and-extract', 14:58:34 INFO - 'populate-webroot', 14:58:34 INFO - 'create-virtualenv', 14:58:34 INFO - 'install', 14:58:34 INFO - 'run-tests'), 14:58:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:58:34 INFO - 'download_minidump_stackwalk': True, 14:58:34 INFO - 'download_symbols': 'ondemand', 14:58:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:58:34 INFO - 'tooltool.py': '/tools/tooltool.py', 14:58:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:58:34 INFO - '/tools/misc-python/virtualenv.py')}, 14:58:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:58:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:58:34 INFO - 'installer_path': 'installer.exe', 14:58:34 INFO - 'log_level': 'info', 14:58:34 INFO - 'log_name': 'talos', 14:58:34 INFO - 'log_to_console': True, 14:58:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:58:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:58:34 INFO - 'opt_config_files': (), 14:58:34 INFO - 'pip_index': False, 14:58:34 INFO - 'sps_profile': False, 14:58:34 INFO - 'sps_profile_interval': 0, 14:58:34 INFO - 'suite': 'dromaeojs', 14:58:34 INFO - 'system_bits': '32', 14:58:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:58:34 INFO - 'title': 'talos-linux64-ix-031', 14:58:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:58:34 INFO - 'use_talos_json': True, 14:58:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:58:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:58:34 INFO - 'work_dir': 'build'} 14:58:34 INFO - ##### 14:58:34 INFO - ##### Running clobber step. 14:58:34 INFO - ##### 14:58:34 INFO - Running pre-action listener: _resource_record_pre_action 14:58:34 INFO - Running main action method: clobber 14:58:34 INFO - rmtree: /builds/slave/test/build 14:58:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:58:34 INFO - Running post-action listener: _resource_record_post_action 14:58:34 INFO - ##### 14:58:34 INFO - ##### Finished clobber step (success) 14:58:34 INFO - ##### 14:58:34 INFO - ##### 14:58:34 INFO - ##### Running read-buildbot-config step. 14:58:34 INFO - ##### 14:58:34 INFO - Running pre-action listener: _resource_record_pre_action 14:58:34 INFO - Running main action method: read_buildbot_config 14:58:34 INFO - Using buildbot properties: 14:58:34 INFO - { 14:58:34 INFO - "project": "", 14:58:34 INFO - "product": "firefox", 14:58:34 INFO - "script_repo_revision": "production", 14:58:34 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 14:58:34 INFO - "repository": "", 14:58:34 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs", 14:58:34 INFO - "buildid": "20160531123017", 14:58:34 INFO - "pgo_build": "True", 14:58:34 INFO - "basedir": "/builds/slave/test", 14:58:34 INFO - "buildnumber": 1, 14:58:34 INFO - "slavename": "talos-linux64-ix-031", 14:58:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:58:34 INFO - "platform": "ubuntu64_hw", 14:58:34 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 14:58:34 INFO - "branch": "larch", 14:58:34 INFO - "repo_path": "projects/larch", 14:58:34 INFO - "revision": "23a96762bc11fdbd26f032d3fcbcbf7417797acb", 14:58:34 INFO - "stage_platform": "linux64-pgo", 14:58:34 INFO - "builduid": "42c1045af760481394f517dee40f15b0", 14:58:34 INFO - "slavebuilddir": "test" 14:58:34 INFO - } 14:58:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:58:34 INFO - Running post-action listener: _resource_record_post_action 14:58:34 INFO - ##### 14:58:34 INFO - ##### Finished read-buildbot-config step (success) 14:58:34 INFO - ##### 14:58:34 INFO - ##### 14:58:34 INFO - ##### Running download-and-extract step. 14:58:34 INFO - ##### 14:58:34 INFO - Running pre-action listener: _resource_record_pre_action 14:58:34 INFO - Running main action method: download_and_extract 14:58:34 INFO - mkdir: /builds/slave/test/build/tests 14:58:34 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')]} 14:58:34 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:58:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:58:35 INFO - Downloaded 1361 bytes. 14:58:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:58:35 INFO - Using the following test package requirements: 14:58:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:58:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:58:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:58:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'jsshell-linux-x86_64.zip'], 14:58:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:58:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:58:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:58:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:58:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:58:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:58:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:58:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:58:35 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:58:36 INFO - Downloaded 24412845 bytes. 14:58:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:58:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:58:37 INFO - Return code: 0 14:58:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:58:37 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')]} 14:58:37 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:58:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:58:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:58:39 INFO - Downloaded 24412845 bytes. 14:58:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:58:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:58:40 INFO - Return code: 0 14:58:40 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')]} 14:58:40 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:58:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:58:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:58:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:58:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:58:41 INFO - Downloaded 10834063 bytes. 14:58:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:58:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:58:41 INFO - Return code: 0 14:58:41 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')]} 14:58:41 INFO - https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:58:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:58:43 INFO - Downloaded 59070319 bytes. 14:58:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:43 INFO - mkdir: /builds/slave/test/properties 14:58:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:58:43 INFO - Writing to file /builds/slave/test/properties/build_url 14:58:43 INFO - Contents: 14:58:43 INFO - build_url:https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:58:44 INFO - Running post-action listener: _resource_record_post_action 14:58:44 INFO - Running post-action listener: set_extra_try_arguments 14:58:44 INFO - ##### 14:58:44 INFO - ##### Finished download-and-extract step (success) 14:58:44 INFO - ##### 14:58:44 INFO - ##### 14:58:44 INFO - ##### Running populate-webroot step. 14:58:44 INFO - ##### 14:58:44 INFO - Running pre-action listener: _resource_record_pre_action 14:58:44 INFO - Running main action method: populate_webroot 14:58:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:58:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:58:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:58:44 INFO - '--tppagecycles', 14:58:44 INFO - '7'], 14:58:44 INFO - 'tests': ['tsvgm']}}, 14:58:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:58:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:58:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:58:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:58:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:58:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['damp', 'tps']}, 14:58:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'tests': ['damp', 'tps']}, 14:58:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['dromaeo_dom']}, 14:58:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:58:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'other-e10s': {'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['a11yr', 14:58:44 INFO - 'ts_paint', 14:58:44 INFO - 'tpaint', 14:58:44 INFO - 'sessionrestore', 14:58:44 INFO - 'sessionrestore_no_auto_restore', 14:58:44 INFO - 'tabpaint']}, 14:58:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['tsvgx', 14:58:44 INFO - 'tsvgr_opacity', 14:58:44 INFO - 'tart', 14:58:44 INFO - 'tscrollx', 14:58:44 INFO - 'cart']}, 14:58:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:58:44 INFO - 'tsvgr_opacity', 14:58:44 INFO - 'tart', 14:58:44 INFO - 'tscrollx', 14:58:44 INFO - 'cart']}, 14:58:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'talos_options': ['--disable-e10s'], 14:58:44 INFO - 'tests': ['tp5o']}, 14:58:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'tests': ['tp5o']}, 14:58:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'talos_options': ['--disable-e10s', 14:58:44 INFO - '--xperf_path', 14:58:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:44 INFO - 'tests': ['tp5n']}, 14:58:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:58:44 INFO - 'talos_options': ['--xperf_path', 14:58:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:58:44 INFO - 'tests': ['tp5n']}}, 14:58:44 INFO - 'talos.zip': {'path': '', 14:58:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:58:44 INFO - Running post-action listener: _resource_record_post_action 14:58:44 INFO - ##### 14:58:44 INFO - ##### Finished populate-webroot step (success) 14:58:44 INFO - ##### 14:58:44 INFO - ##### 14:58:44 INFO - ##### Running create-virtualenv step. 14:58:44 INFO - ##### 14:58:44 INFO - Running pre-action listener: _resource_record_pre_action 14:58:44 INFO - Running main action method: create_virtualenv 14:58:44 INFO - Creating virtualenv /builds/slave/test/build/venv 14:58:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:58:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:58:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:58:44 INFO - Using real prefix '/usr' 14:58:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:58:44 INFO - Installing distribute.............................................................................................................................................................................................done. 14:58:46 INFO - Installing pip.................done. 14:58:46 INFO - Return code: 0 14:58:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:46 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:46 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 14:58:46 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 14:58:46 INFO - Using env: {'DISPLAY': ':0', 14:58:46 INFO - 'HOME': '/home/cltbld', 14:58:46 INFO - 'LANG': 'en_US.UTF-8', 14:58:46 INFO - 'LANGUAGE': 'en_US:en', 14:58:46 INFO - 'LOGNAME': 'cltbld', 14:58:46 INFO - 'MAIL': '/var/mail/cltbld', 14:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:46 INFO - 'MOZ_NO_REMOTE': '1', 14:58:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:46 INFO - 'NO_EM_RESTART': '1', 14:58:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:46 INFO - 'PWD': '/builds/slave/test', 14:58:46 INFO - 'SHELL': '/bin/bash', 14:58:46 INFO - 'SHLVL': '1', 14:58:46 INFO - 'TERM': 'linux', 14:58:46 INFO - 'TMOUT': '86400', 14:58:46 INFO - 'USER': 'cltbld', 14:58:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:46 INFO - '_': '/tools/buildbot/bin/python'} 14:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:46 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 14:58:46 INFO - Cleaning up... 14:58:46 INFO - Return code: 0 14:58:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:46 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:46 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 14:58:46 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 14:58:46 INFO - Using env: {'DISPLAY': ':0', 14:58:46 INFO - 'HOME': '/home/cltbld', 14:58:46 INFO - 'LANG': 'en_US.UTF-8', 14:58:46 INFO - 'LANGUAGE': 'en_US:en', 14:58:46 INFO - 'LOGNAME': 'cltbld', 14:58:46 INFO - 'MAIL': '/var/mail/cltbld', 14:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:46 INFO - 'MOZ_NO_REMOTE': '1', 14:58:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:46 INFO - 'NO_EM_RESTART': '1', 14:58:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:46 INFO - 'PWD': '/builds/slave/test', 14:58:46 INFO - 'SHELL': '/bin/bash', 14:58:46 INFO - 'SHLVL': '1', 14:58:46 INFO - 'TERM': 'linux', 14:58:46 INFO - 'TMOUT': '86400', 14:58:46 INFO - 'USER': 'cltbld', 14:58:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:46 INFO - '_': '/tools/buildbot/bin/python'} 14:58:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:47 INFO - Downloading/unpacking psutil>=3.1.1 14:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:58:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:49 INFO - Installing collected packages: psutil 14:58:49 INFO - Running setup.py install for psutil 14:58:49 INFO - building 'psutil._psutil_linux' extension 14:58:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:58:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:58:49 INFO - building 'psutil._psutil_posix' extension 14:58:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:58:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:58:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:58:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:58:49 INFO - Successfully installed psutil 14:58:49 INFO - Cleaning up... 14:58:49 INFO - Return code: 0 14:58:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:58:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:49 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:49 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 14:58:49 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 14:58:49 INFO - Using env: {'DISPLAY': ':0', 14:58:49 INFO - 'HOME': '/home/cltbld', 14:58:49 INFO - 'LANG': 'en_US.UTF-8', 14:58:49 INFO - 'LANGUAGE': 'en_US:en', 14:58:49 INFO - 'LOGNAME': 'cltbld', 14:58:49 INFO - 'MAIL': '/var/mail/cltbld', 14:58:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:49 INFO - 'MOZ_NO_REMOTE': '1', 14:58:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:49 INFO - 'NO_EM_RESTART': '1', 14:58:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:49 INFO - 'PWD': '/builds/slave/test', 14:58:49 INFO - 'SHELL': '/bin/bash', 14:58:49 INFO - 'SHLVL': '1', 14:58:49 INFO - 'TERM': 'linux', 14:58:49 INFO - 'TMOUT': '86400', 14:58:49 INFO - 'USER': 'cltbld', 14:58:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:49 INFO - '_': '/tools/buildbot/bin/python'} 14:58:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:58:49 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 14:58:49 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 14:58:49 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 14:58:49 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 14:58:49 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 14:58:49 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 14:58:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:58:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:58:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:58:51 INFO - Installing collected packages: mozsystemmonitor 14:58:51 INFO - Running setup.py install for mozsystemmonitor 14:58:51 INFO - Successfully installed mozsystemmonitor 14:58:51 INFO - Cleaning up... 14:58:51 INFO - Return code: 0 14:58:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:58:51 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')]} 14:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:51 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')]} 14:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:51 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:51 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 14:58:51 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 14:58:52 INFO - Using env: {'DISPLAY': ':0', 14:58:52 INFO - 'HOME': '/home/cltbld', 14:58:52 INFO - 'LANG': 'en_US.UTF-8', 14:58:52 INFO - 'LANGUAGE': 'en_US:en', 14:58:52 INFO - 'LOGNAME': 'cltbld', 14:58:52 INFO - 'MAIL': '/var/mail/cltbld', 14:58:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:52 INFO - 'MOZ_NO_REMOTE': '1', 14:58:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:52 INFO - 'NO_EM_RESTART': '1', 14:58:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:52 INFO - 'PWD': '/builds/slave/test', 14:58:52 INFO - 'SHELL': '/bin/bash', 14:58:52 INFO - 'SHLVL': '1', 14:58:52 INFO - 'TERM': 'linux', 14:58:52 INFO - 'TMOUT': '86400', 14:58:52 INFO - 'USER': 'cltbld', 14:58:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:52 INFO - '_': '/tools/buildbot/bin/python'} 14:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:58:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:58:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:58:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:58:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:58:54 INFO - Downloading docopt-0.6.1.tar.gz 14:58:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:58:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:58:54 INFO - Running setup.py install for blobuploader 14:58:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:58:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:58:54 INFO - Running setup.py install for requests 14:58:55 INFO - Running setup.py install for docopt 14:58:55 INFO - Successfully installed blobuploader requests docopt 14:58:55 INFO - Cleaning up... 14:58:55 INFO - Return code: 0 14:58:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:55 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:55 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 14:58:55 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 14:58:55 INFO - Using env: {'DISPLAY': ':0', 14:58:55 INFO - 'HOME': '/home/cltbld', 14:58:55 INFO - 'LANG': 'en_US.UTF-8', 14:58:55 INFO - 'LANGUAGE': 'en_US:en', 14:58:55 INFO - 'LOGNAME': 'cltbld', 14:58:55 INFO - 'MAIL': '/var/mail/cltbld', 14:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:55 INFO - 'MOZ_NO_REMOTE': '1', 14:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:55 INFO - 'NO_EM_RESTART': '1', 14:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:55 INFO - 'PWD': '/builds/slave/test', 14:58:55 INFO - 'SHELL': '/bin/bash', 14:58:55 INFO - 'SHLVL': '1', 14:58:55 INFO - 'TERM': 'linux', 14:58:55 INFO - 'TMOUT': '86400', 14:58:55 INFO - 'USER': 'cltbld', 14:58:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:55 INFO - '_': '/tools/buildbot/bin/python'} 14:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:55 INFO - Running setup.py (path:/tmp/pip-sJ0u7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:55 INFO - Running setup.py (path:/tmp/pip-FeXSO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:55 INFO - Running setup.py (path:/tmp/pip-tg689T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:55 INFO - Running setup.py (path:/tmp/pip-KSGYHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:55 INFO - Running setup.py (path:/tmp/pip-qgHkdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:56 INFO - Running setup.py (path:/tmp/pip-xTRSG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:56 INFO - Running setup.py (path:/tmp/pip-f0kSMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:56 INFO - Running setup.py (path:/tmp/pip-sZyRDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:56 INFO - Running setup.py (path:/tmp/pip-xb1ESH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:56 INFO - Running setup.py (path:/tmp/pip-Yd649W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:56 INFO - Running setup.py (path:/tmp/pip-A_rRcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:56 INFO - Running setup.py (path:/tmp/pip-4XXS3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:56 INFO - Running setup.py (path:/tmp/pip-PMffz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:56 INFO - Running setup.py (path:/tmp/pip-0DaaMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:56 INFO - Running setup.py (path:/tmp/pip-b9jE1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:58:56 INFO - Running setup.py (path:/tmp/pip-vVZXwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:58:56 INFO - Running setup.py (path:/tmp/pip-gRbydb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:58:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:58:56 INFO - Running setup.py install for manifestparser 14:58:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for mozcrash 14:58:57 INFO - Running setup.py install for mozdebug 14:58:57 INFO - Running setup.py install for mozdevice 14:58:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for mozfile 14:58:57 INFO - Running setup.py install for mozhttpd 14:58:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for mozinfo 14:58:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for mozInstall 14:58:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for mozleak 14:58:57 INFO - Running setup.py install for mozlog 14:58:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:58:57 INFO - Running setup.py install for moznetwork 14:58:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Running setup.py install for mozprocess 14:58:58 INFO - Running setup.py install for mozprofile 14:58:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Running setup.py install for mozrunner 14:58:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Running setup.py install for mozscreenshot 14:58:58 INFO - Running setup.py install for moztest 14:58:58 INFO - Running setup.py install for mozversion 14:58:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:58:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:58:58 INFO - Cleaning up... 14:58:58 INFO - Return code: 0 14:58:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:58: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')]} 14:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58: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')]} 14:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:58:58 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:58:58 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 14:58:58 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 14:58:58 INFO - Using env: {'DISPLAY': ':0', 14:58:58 INFO - 'HOME': '/home/cltbld', 14:58:58 INFO - 'LANG': 'en_US.UTF-8', 14:58:58 INFO - 'LANGUAGE': 'en_US:en', 14:58:58 INFO - 'LOGNAME': 'cltbld', 14:58:58 INFO - 'MAIL': '/var/mail/cltbld', 14:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:58:58 INFO - 'MOZ_NO_REMOTE': '1', 14:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:58:58 INFO - 'NO_EM_RESTART': '1', 14:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:58:58 INFO - 'PWD': '/builds/slave/test', 14:58:58 INFO - 'SHELL': '/bin/bash', 14:58:58 INFO - 'SHLVL': '1', 14:58:58 INFO - 'TERM': 'linux', 14:58:58 INFO - 'TMOUT': '86400', 14:58:58 INFO - 'USER': 'cltbld', 14:58:58 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:58:58 INFO - '_': '/tools/buildbot/bin/python'} 14:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:58:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:58:58 INFO - Running setup.py (path:/tmp/pip-4aR1vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:58:59 INFO - Running setup.py (path:/tmp/pip-TIFlnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:58:59 INFO - Running setup.py (path:/tmp/pip-KCHz8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:58:59 INFO - Running setup.py (path:/tmp/pip-jktbxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:58:59 INFO - Running setup.py (path:/tmp/pip-B2YaJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:58:59 INFO - Running setup.py (path:/tmp/pip-k_ylzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:58:59 INFO - Running setup.py (path:/tmp/pip-OgzD5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:58:59 INFO - Running setup.py (path:/tmp/pip-h3xC0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:58:59 INFO - Running setup.py (path:/tmp/pip-LfIqON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:58:59 INFO - Running setup.py (path:/tmp/pip-mpppQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:58:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:58:59 INFO - Running setup.py (path:/tmp/pip-uXCUfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:58:59 INFO - Running setup.py (path:/tmp/pip-JYpeCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:58:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:58:59 INFO - Running setup.py (path:/tmp/pip-AN5Hiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:58:59 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:58:59 INFO - Running setup.py (path:/tmp/pip-nGTXY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:58:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 14:58:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:58:59 INFO - Running setup.py (path:/tmp/pip-G44v_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:59:00 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)) 14:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:59:00 INFO - Running setup.py (path:/tmp/pip-DpRD3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:59:00 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)) 14:59:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:59:00 INFO - Running setup.py (path:/tmp/pip-PVULzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:59:00 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)) 14:59:00 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)) 14:59:00 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)) 14:59:00 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)) 14:59:00 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)) 14:59:00 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)) 14:59:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:59:00 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 14:59:00 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 14:59:00 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 14:59:00 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 14:59:00 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 14:59:00 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 14:59:02 INFO - Downloading blessings-1.6.tar.gz 14:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:59:02 INFO - Installing collected packages: blessings 14:59:02 INFO - Running setup.py install for blessings 14:59:02 INFO - Successfully installed blessings 14:59:02 INFO - Cleaning up... 14:59:02 INFO - Return code: 0 14:59:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:59:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:59:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:59:02 INFO - Reading from file tmpfile_stdout 14:59:02 INFO - Current package versions: 14:59:02 INFO - argparse == 1.2.1 14:59:02 INFO - blessings == 1.6 14:59:02 INFO - blobuploader == 1.2.4 14:59:02 INFO - docopt == 0.6.1 14:59:02 INFO - manifestparser == 1.1 14:59:02 INFO - mozInstall == 1.12 14:59:02 INFO - mozcrash == 0.17 14:59:02 INFO - mozdebug == 0.1 14:59:02 INFO - mozdevice == 0.48 14:59:02 INFO - mozfile == 1.2 14:59:02 INFO - mozhttpd == 0.7 14:59:02 INFO - mozinfo == 0.9 14:59:02 INFO - mozleak == 0.1 14:59:02 INFO - mozlog == 3.2 14:59:02 INFO - moznetwork == 0.27 14:59:02 INFO - mozprocess == 0.22 14:59:02 INFO - mozprofile == 0.28 14:59:02 INFO - mozrunner == 6.11 14:59:02 INFO - mozscreenshot == 0.1 14:59:02 INFO - mozsystemmonitor == 0.3 14:59:02 INFO - moztest == 0.7 14:59:02 INFO - mozversion == 1.4 14:59:02 INFO - psutil == 3.1.1 14:59:02 INFO - requests == 1.2.3 14:59:02 INFO - wsgiref == 0.1.2 14:59:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:59:02 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 14:59:02 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 14:59:02 INFO - Using env: {'DISPLAY': ':0', 14:59:02 INFO - 'HOME': '/home/cltbld', 14:59:02 INFO - 'LANG': 'en_US.UTF-8', 14:59:02 INFO - 'LANGUAGE': 'en_US:en', 14:59:02 INFO - 'LOGNAME': 'cltbld', 14:59:02 INFO - 'MAIL': '/var/mail/cltbld', 14:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:02 INFO - 'MOZ_NO_REMOTE': '1', 14:59:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:02 INFO - 'NO_EM_RESTART': '1', 14:59:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:02 INFO - 'PWD': '/builds/slave/test', 14:59:02 INFO - 'SHELL': '/bin/bash', 14:59:02 INFO - 'SHLVL': '1', 14:59:02 INFO - 'TERM': 'linux', 14:59:02 INFO - 'TMOUT': '86400', 14:59:02 INFO - 'USER': 'cltbld', 14:59:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:02 INFO - '_': '/tools/buildbot/bin/python'} 14:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 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)) 14:59:02 INFO - Cleaning up... 14:59:02 INFO - Return code: 0 14:59:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:02 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 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:59:02 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 14:59:02 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 14:59:02 INFO - Using env: {'DISPLAY': ':0', 14:59:02 INFO - 'HOME': '/home/cltbld', 14:59:02 INFO - 'LANG': 'en_US.UTF-8', 14:59:02 INFO - 'LANGUAGE': 'en_US:en', 14:59:02 INFO - 'LOGNAME': 'cltbld', 14:59:02 INFO - 'MAIL': '/var/mail/cltbld', 14:59:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:02 INFO - 'MOZ_NO_REMOTE': '1', 14:59:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:02 INFO - 'NO_EM_RESTART': '1', 14:59:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:02 INFO - 'PWD': '/builds/slave/test', 14:59:02 INFO - 'SHELL': '/bin/bash', 14:59:02 INFO - 'SHLVL': '1', 14:59:02 INFO - 'TERM': 'linux', 14:59:02 INFO - 'TMOUT': '86400', 14:59:02 INFO - 'USER': 'cltbld', 14:59:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:59:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:02 INFO - '_': '/tools/buildbot/bin/python'} 14:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:02 INFO - Downloading/unpacking jsonschema 14:59:02 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 14:59:02 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 14:59:02 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 14:59:02 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 14:59:02 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 14:59:02 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 14:59:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:59:04 INFO - Downloading/unpacking functools32 (from jsonschema) 14:59:04 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 14:59:04 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 14:59:04 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 14:59:04 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 14:59:04 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 14:59:04 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 14:59:05 INFO - Downloading functools32-3.2.3-2.tar.gz 14:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:59:05 INFO - warning: no files found matching '*.txt' 14:59:05 INFO - no previously-included directories found matching 'build' 14:59:05 INFO - no previously-included directories found matching 'dist' 14:59:05 INFO - no previously-included directories found matching '.git*' 14:59:05 INFO - Installing collected packages: jsonschema, functools32 14:59:05 INFO - Running setup.py install for functools32 14:59:05 INFO - warning: no files found matching '*.txt' 14:59:05 INFO - no previously-included directories found matching 'build' 14:59:05 INFO - no previously-included directories found matching 'dist' 14:59:05 INFO - no previously-included directories found matching '.git*' 14:59:05 INFO - Successfully installed jsonschema functools32 14:59:05 INFO - Cleaning up... 14:59:05 INFO - Return code: 0 14:59:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:59:05 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7effbb453f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2aad770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, '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': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', '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 14:59:05 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', 'flake8'] in /builds/slave/test/build 14:59:05 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 flake8 14:59:05 INFO - Using env: {'DISPLAY': ':0', 14:59:05 INFO - 'HOME': '/home/cltbld', 14:59:05 INFO - 'LANG': 'en_US.UTF-8', 14:59:05 INFO - 'LANGUAGE': 'en_US:en', 14:59:05 INFO - 'LOGNAME': 'cltbld', 14:59:05 INFO - 'MAIL': '/var/mail/cltbld', 14:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:05 INFO - 'MOZ_NO_REMOTE': '1', 14:59:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:05 INFO - 'NO_EM_RESTART': '1', 14:59:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:05 INFO - 'PWD': '/builds/slave/test', 14:59:05 INFO - 'SHELL': '/bin/bash', 14:59:05 INFO - 'SHLVL': '1', 14:59:05 INFO - 'TERM': 'linux', 14:59:05 INFO - 'TMOUT': '86400', 14:59:05 INFO - 'USER': 'cltbld', 14:59:05 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:05 INFO - '_': '/tools/buildbot/bin/python'} 14:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:59:05 INFO - Downloading/unpacking flake8 14:59:05 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 14:59:05 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 14:59:05 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 14:59:05 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 14:59:05 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 14:59:05 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 14:59:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:59:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:59:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:07 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 14:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:59:08 INFO - no previously-included directories found matching 'docs/_build' 14:59:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:59:08 INFO - Running setup.py install for pep8 14:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:59:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:59:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:59:08 INFO - no previously-included directories found matching 'docs/_build' 14:59:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:59:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:59:08 INFO - Cleaning up... 14:59:08 INFO - Return code: 0 14:59:08 INFO - Running post-action listener: _resource_record_post_action 14:59:08 INFO - Running post-action listener: _start_resource_monitoring 14:59:08 INFO - Starting resource monitoring. 14:59:08 INFO - ##### 14:59:08 INFO - ##### Finished create-virtualenv step (success) 14:59:08 INFO - ##### 14:59:08 INFO - ##### 14:59:08 INFO - ##### Running install step. 14:59:08 INFO - ##### 14:59:08 INFO - Running pre-action listener: _resource_record_pre_action 14:59:08 INFO - Running main action method: install 14:59:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:59:08 INFO - Reading from file tmpfile_stdout 14:59:08 INFO - Detecting whether we're running mozinstall >=1.0... 14:59:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:59:08 INFO - Reading from file tmpfile_stdout 14:59:08 INFO - Output received: 14:59:08 INFO - Usage: mozinstall [options] installer 14:59:08 INFO - Options: 14:59:08 INFO - -h, --help show this help message and exit 14:59:08 INFO - -d DEST, --destination=DEST 14:59:08 INFO - Directory to install application into. [default: 14:59:08 INFO - "/builds/slave/test"] 14:59:08 INFO - --app=APP Application being installed. [default: firefox] 14:59:08 INFO - mkdir: /builds/slave/test/build/application 14:59:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:59:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:59:25 INFO - Reading from file tmpfile_stdout 14:59:25 INFO - Output received: 14:59:25 INFO - /builds/slave/test/build/application/firefox/firefox 14:59:25 INFO - Running post-action listener: _resource_record_post_action 14:59:25 INFO - ##### 14:59:25 INFO - ##### Finished install step (success) 14:59:25 INFO - ##### 14:59:25 INFO - ##### 14:59:25 INFO - ##### Running run-tests step. 14:59:25 INFO - ##### 14:59:25 INFO - Running pre-action listener: _resource_record_pre_action 14:59:25 INFO - Running main action method: run_tests 14:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:59:25 INFO - Python 2.7.3 14:59:25 INFO - Return code: 0 14:59:25 INFO - grabbing minidump binary from tooltool 14:59:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 14:59:25 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 0x2b3c9c0>, '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 0x2b3d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b3dd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:59:25 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 14:59:25 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 14:59:25 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 14:59:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:59:25 INFO - Return code: 0 14:59:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:59:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:59:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:59:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:59:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:59:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:59:26 INFO - Return code: 0 14:59:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 14:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 14:59:26 INFO - Using env: {'DISPLAY': ':0', 14:59:26 INFO - 'HOME': '/home/cltbld', 14:59:26 INFO - 'LANG': 'en_US.UTF-8', 14:59:26 INFO - 'LANGUAGE': 'en_US:en', 14:59:26 INFO - 'LOGNAME': 'cltbld', 14:59:26 INFO - 'MAIL': '/var/mail/cltbld', 14:59:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:59:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:59:26 INFO - 'MOZ_NO_REMOTE': '1', 14:59:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:59:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:59:26 INFO - 'NO_EM_RESTART': '1', 14:59:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:59:26 INFO - 'PWD': '/builds/slave/test', 14:59:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:59:26 INFO - 'SHELL': '/bin/bash', 14:59:26 INFO - 'SHLVL': '1', 14:59:26 INFO - 'TERM': 'linux', 14:59:26 INFO - 'TMOUT': '86400', 14:59:26 INFO - 'USER': 'cltbld', 14:59:26 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110', 14:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:59:26 INFO - '_': '/tools/buildbot/bin/python'} 14:59:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 14:59:26 INFO - mozversion application_buildid: 20160531123017 14:59:26 INFO - mozversion application_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:59:26 INFO - mozversion application_display_name: Nightly 14:59:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:59:26 INFO - mozversion application_name: Firefox 14:59:26 INFO - mozversion application_remotingname: firefox 14:59:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:59:26 INFO - mozversion application_vendor: Mozilla 14:59:26 INFO - mozversion application_version: 49.0a1 14:59:26 INFO - mozversion platform_buildid: 20160531123017 14:59:26 INFO - mozversion platform_changeset: 23a96762bc11fdbd26f032d3fcbcbf7417797acb 14:59:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:59:26 INFO - mozversion platform_version: 49.0a1 14:59:26 INFO - using testdate: 1464731966 14:59:26 INFO - actual date: 1464731966 14:59:26 INFO - starting webserver on 'localhost:55710' 14:59:26 INFO - SUITE-START | Running 2 tests 14:59:26 INFO - TEST-START | dromaeo_css 14:59:26 INFO - operating with platform_type : linux_ 14:59:26 INFO - Initialising browser for dromaeo_css test... 14:59:26 INFO - TEST-INFO | started process 30783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc37IYw/profile http://localhost:55710/getInfo.html) 14:59:30 INFO - PROCESS | 30783 | __metrics Screen width/height:1600/1200 14:59:30 INFO - PROCESS | 30783 | colorDepth:24 14:59:30 INFO - PROCESS | 30783 | Browser inner width/height: 1024/697 14:59:30 INFO - PROCESS | 30783 | __metrics 14:59:31 ERROR - PROCESS | 30783 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:59:32 INFO - TEST-INFO | 30783: exit 0 14:59:32 INFO - Browser initialized. 14:59:32 INFO - Running cycle 1/1 for dromaeo_css test... 14:59:32 INFO - TEST-INFO | started process 30864 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc37IYw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:59:44 INFO - PROCESS | 30864 | RSS: Main: 155885568 14:59:44 INFO - PROCESS | 30864 | 15:00:35 INFO - PROCESS | 30864 | 1464732035712 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:02:43 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55710/tests/dromaeo/cssquery-ext.html) 15:02:44 INFO - PROCESS | 30864 | RSS: Main: 247525376 15:02:44 INFO - PROCESS | 30864 | 15:05:46 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-ext.html (next: http://localhost:55710/tests/dromaeo/cssquery-jquery.html) 15:05:47 INFO - PROCESS | 30864 | RSS: Main: 244658176 15:05:47 INFO - PROCESS | 30864 | 15:08:49 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55710/tests/dromaeo/cssquery-mootools.html) 15:08:50 INFO - PROCESS | 30864 | RSS: Main: 239681536 15:08:50 INFO - PROCESS | 30864 | 15:11:51 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55710/tests/dromaeo/cssquery-prototype.html) 15:11:52 INFO - PROCESS | 30864 | RSS: Main: 244371456 15:11:52 INFO - PROCESS | 30864 | 15:14:55 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55710/tests/dromaeo/cssquery-yui.html) 15:14:55 INFO - PROCESS | 30864 | RSS: Main: 215629824 15:14:55 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | Cycle 1(1): loaded http://localhost:55710/tests/dromaeo/cssquery-yui.html (next: http://localhost:55710/tests/dromaeo/cssquery-dojo.html) 15:17:58 INFO - PROCESS | 30864 | RSS: Main: 276463616 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | __start_tp_report 15:17:58 INFO - PROCESS | 30864 | _x_x_mozilla_page_load 15:17:58 INFO - PROCESS | 30864 | _x_x_mozilla_page_load_details 15:17:58 INFO - PROCESS | 30864 | |i|pagename|runs| 15:17:58 INFO - PROCESS | 30864 | |0;dojo.html;3145.2923092304227;3159.7630177736673;3177.809331440113;3203.1671765341002;3228.9192770180744;5326.227696983935;5393.2719082923895;5456.127019676853;5530.668159910403;5593.580481463896;5437.864053398667;5466.726663666821;5490.423505531921;5556.249906262653;5558.110702287635;4906.901861962768;4968.503149685039;4975.751212439363;4990.951900100965;5082.695038297714;5012.573931215851;5051.5453609175165;5066.18940969445;5087.872803179913;5115.079285728586;4829.468758436569;4870.025994801036;4882.194437917739;4891.75541222941;4962;4935.925961110586;4941.654084214107;4961.727105009223;4967.478414766434;5008.474110218411;4964.007198560266;4980.9252861207115;4990.6257030722845;5002.349694539723;5030.421501527335;5289.497497737709;5374.059539580558;5441.183822426668;5447.918281225785;5500.614956953015;5846.619969701956;5893.263342082239;5900.940990590082;5903.439173278532;5904.498117660004;5896.469317761422;5905.527557795409;5938.703064846741;5953.404659534055;5953.553483488712;5259.789608415659;5419.945800541984;5490.862728431781;5497.532709719679;5550.528205102531;6031.616866298154;6059.333473317932;6084.421979911901;6085.665288409092;6086;5507.9449205507435;5777.422257774189;5818.069108942549;5824.854378640483;5834.591578589458;5660.094384898396;5730.770769169187;5733.77064917407;5741.483266506035;5809.186713860063;5707.800226992035;5709.200711900337;5715.342735585377;5719.399463056402;5725.656460612377;5575.080111781511;5688.82933511987;5754.74103665336;5756.107803290497;5783.248177736868;4441.400410944546;4453.799579018952;4454.086912182995;4492.191405547033;4496.6177874881005;3958.2281455116554;3967.7420967637013;3976.304146774303;3982.78094704794;4002.0195052212393;5308.973455132699;5363.410024897258;5385.838424847261;5488.423715509894;5494.1758736189095;5814.040683287295;5844.211031510777;5869.911951320733;5871.970640146772;5931.11033344993;5440.347158341025;5450.727463626882;5454.863628409322;5457.890842183214;5459.454054594509;4930.753462326869;5010.198368261061;5028.949710502921;5035.521625445577;5074.06129865976;6113.354685976905;6120.508083830883;6122.428649698624;6126.639818174636;6127.128444391265;5894.440028197314;5925.762969481172;5928.822135335947;5930.762769489172;5980.342162362137;5818.359980402068;6017.548683848641;6055.9091613626715;6062.42406971337;6071.908921366095;5725;5754.136879468113;5758.078707406795;5769.91345129815;5772.336181339199;5870.119482077723;5889.587728858939;5891.05743081105;5892.6759028252145;5896.7936122235515;5542.2795036644975;5638.182463542846;5675.7445914933105;5679.204911312337;5709.942900570941;5691.117876729114;5698.31620205578;5701.942980570141;5706.457886500776;5710.714464276686;5479.150731636602;5612.494875461228;5615;5621.943780562142;5624.521915637217;5685.459881311269;5732.828015159552;5753.8561535961935;5823.039198532195;5825.70871456434;5650.689212093374;5651;5652.463016013472;5659.349174844946;5695.174199741097 15:17:58 INFO - PROCESS | 30864 | |1;ext.html;613.5306490534741;629.0721186250281;644.7066584703961;647.8153726188037;648.4650163615019;31139.532907006385;34231.99999999997;34285.82857085714;42362.57637423625;43554.12891742163;45817;46327.30509042367;46408;46409.30386044212;46431.76784116083;8638.179372959574;8797.604107815132;8802.86795698065;8818.64725410986;8822.86765698515;8316.875246871301;8702.608382622797;8703.260222881032;8710.520921349324;8711.520866352348;8748.387612867102;8766.912330876674;8767.167119123706;8767.736967890942;8773.25427338677;8684.218420342168;8757.56212189388;8760.255378292853;8762.34282428814;8762.737117886441;8661.480311181349;8729.519876406795;8736.039035706068;8736.60685269158;8739.344549158775;42090.57909420928;42567.57432425698;42611.36082958758;42654.78672606617;42685.57314426847;3413.4709120086427;3623.8188090595363;3730.9253814923823;3978.900527486836;4092.4884389451317;31787.523187152212;32195;32298.354032919444;32574.185645359055;32835.83582082098;4372.409724687158;4405.405270288502;4433.13553856998;4463.486699029589;4528.18492671319;256328;257017;257447;257556;257765;53751.19373209405;61143.388566114656;61215.69392153011;61581.692091539255;61599.692001539705;62988.685056574424;64437;64657.67671161708;69586.65206673935;69778;15662.9216853915;15869.920650396905;15875.682486350208;15903.045817251003;15911.68176636484;10979.28634638746;11341.716457088474;11488.138389620812;11609.76780464386;11890.702732431759;18669.346572869883;18716.719249211274;18797.81202187988;18800.247990080516;18833.52916177079;6012.896909636722;6040.969795150996;6150.415710507495;6200.100985357189;6213.906791398133;1036.7356324137447;1047.3297089862494;1133.6849254864483;1139.9601013964636;1157.1552766480515;32444.513332300034;32816.1795955103;32919.50620740691;32930;33426.49860252098;1158.3744777820052;1161.2045748662194;1191.8677256606259;1209.5343292832147;1218.5491368193825;16958.321667133176;17328.220230089675;17341.929555590017;17345.913270433568;17425.215865286093;498046;498539;498836;499226;499867;59340.70329648324;59700.40299596948;59765;59800.401995980355;59805;1269.2511418263084;1271.5422447918743;1341.8188544546556;1349.5748839115647;1380.061558140454;32066.679333206368;32834.835825820715;32847.34305313928;33396.1650958728;33928.66071339255;51222;51446.742766285926;51712.224316636035;51750.741246293524;51808.48191518037;400538;402050;403455;403476;403516;36957.81521092377;41500.377494338216;42379.364309535995;42774;45653.31520027136;49704.25443618276;49945;49968;50259.497405026945;51335.48664513307;41648.1670366597;42145.36781948212;42205.155896881275;42938.14123717446;43441;40603.98490037773;41744.424328034154;41879.79060104802;41938.580614193466;41964.790176048926;38300.61699383082;38572.6142738569;38916;38939.61060389473;40310.79844600758;34824;35884.820575896956;36129.638703612625;36293.63706362902;36585.63414365822;28378.2905427366;28585.42829143447;28586.714132858404;28798.280042999093;28962 15:17:58 INFO - PROCESS | 30864 | |2;jquery.html;395.7111308744618;398.65317174058566;402.9375446805746;408.47918903398164;417.20314199878237;18097.547561310974;19600.70598941017;19615;19784.505387365287;20557.01923221132;19312;19565.608687826272;19873.60252794943;20091.799082009176;20124.496887577818;5578.63738856975;5633.605647604659;5788.131780232978;5815.505682017034;5829.504492118175;5719.056355701305;5720.227769251161;5751;5779.595428320019;5789.102689083199;5526.143447765603;5684.033714268564;5715.828525144252;5730.914036289459;5733.713314334287;5502.174673798922;5640.012997725402;5651.547876169897;5694.145878118274;5696.829095127174;5150.716710580917;5156.510131537498;5167.199084141964;5177.663451875654;5178.17149256121;22809.201677941193;22850.657240141412;23241.88379058111;23294.7670523296;23359;2350.247920665388;2355.6702061711376;2396.5686176488225;2399.1722855614785;2460.6555082288314;7418.406527477789;9160.633574657006;14332.49836255726;14412.351444185037;14975.176365299903;1968.1143485431612;2031.7561892572985;2037.5313677854176;2055.4553043443498;2070.27540360874;79822.20177798206;79996;80386.5980670093;80444.19555804426;82623;10479.633212837514;10626.415547144827;10968.287061340987;10969.396683182342;11048.337099774039;14977.326020329112;15154.848451515565;15155.181378234462;15156.924215379073;15164.545063648107;7747.767566973;7875.684972601031;7959.920400795918;8059.622411388655;8125.634346454423;7581.166071732105;7622.719120883515;7623.428242881695;7711.149324162161;7765.107012693613;6753.797386078426;6774.559653622498;6808.4212841908;6820.727170913207;6855.417289530346;1869.7662792150982;1890.1683259365834;1912.6844070728173;1936.5061909213061;1965.626530959113;867.6746220167437;950.4867371619387;981.8036392721484;1034.818906691326;1057.772578895541;18704.906475467535;19749.802501974795;20018.799812001984;20054.197832086844;20375.796242037686;893.3389291923929;950.6862735297335;999.8000399920045;1051.2273478992934;1092.808758467265;10864.619738309122;11544.711382215512;11564.595239166589;11565.19043666952;11588.478518466689;471656;488229;494133;495707;502675;96008.51995740116;98676.5066174679;99536.5023174894;99768.5011574952;100307.49846250722;1007.1640538353149;1013.9442330671884;1035.135661722455;1058.6030238660503;1059.4490864750287;17276.74084888753;17687.204075816877;17970.281188752302;18184.09079545991;18200.81799182044;40881;41313.58686413218;42336.78831605946;42891.142177155656;43142.35286470768;56483;57597;58096;58453.415465844795;59141;8373.581320933807;8531.786705332417;8607.82784344297;8625.956870215608;8639.179277968582;9590.944996050568;12928;12948.546800861925;13013.284269365276;13447.596572102853;11220.607278745205;11236;11642.94178529102;11801.114916381133;12080.39598020113;11074.169437292074;11240.494177762184;11422.657320280405;11479;11556.42217889119;11421.942890285496;11695.356755378196;11781;12059.638210853689;12060.276383417024;7397;7569.886451703119;8535.189157030072;8680.262177714965;8715.782105447415;11711.590094346657;11906.702332441759;12096.637100886988;12368.319742414255;12570.1200915935 15:17:58 INFO - PROCESS | 30864 | |3;mootools.html;1346.7373862096883;1515.7120147172036;1542.1980570103547;1669.607642204082;1819.4268805326321;8475.110113438093;8797.956010219948;8886;9130.634774609007;9135.862962055558;8903.37676362655;9088.772780680487;9111;9123;9128.680496182635;2336.135629816966;2354.5290941811622;2356.587597170497;2360.7403185649564;2361.645753137026;2402.711674599042;2432.1001229545004;2445.40087678519;2448.7796098351146;2453.3989172652723;2324.848884822481;2344.9765502345012;2355.1639168095335;2379.286214135761;2383.4160630645515;2209.9005544750526;2213.18112298449;2227.8217742580637;2228.944276393095;2292.197730794225;2272.579572779041;2275.8179345652306;2278.054607337953;2279.1453205048106;2279.282026161754;8947.150020748019;9000.189982901506;9001.729948101567;9011.504367259799;9247.583858726306;5281.260623512711;5386.78452861885;5391.595630327741;5394.109971854639;5404.297441332641;3340.515625234352;3376.7129793967542;3417.0603084151808;3430.1939044324563;3433.8454769535433;1076.4832880217461;1122.8428020077197;1142.8285757136414;1155.4684844971323;1159.6463078760974;13505.864941350657;13599.184048956995;13632.250226237553;13647;13657;4623.029163875623;4655.394798676218;4755.952440475551;4756.9524304756505;4768.094062128184;10472;10503.579856805794;10524.947375263075;10532.947335263274;10540.525676344583;5208.296879921238;5255.185446255837;5258.500442458037;5269.868253293622;5270.183121616155;4371.519132895379;4785.9042819144115;4934.506549345037;4976.377952755905;4982.3522942018035;3938.7636741795586;3977.7215594908657;4001.599840015975;4001.619846114673;4031.133306339151;1150.9597164099216;1174.0842143128373;1174.812030075201;1180.2800291821982;1187.619961612293;747.312472525278;918.7427520294194;925.2967744514218;951.5908159491347;952.5523004188159;5751.424857514216;5772.191893134965;5861.970690146607;5883.411658834168;5923.407659234128;478.7127723366022;491.44466752569366;496.6027178257452;497.53729032000587;656.4354654996699;3919.784411857318;4135.255653982252;4146.606072423115;4149.834006639761;4173;11113.777724445466;11458.54165833374;11479.540818367339;11535.942320288344;11571.305721656727;11174.10607151426;11366.772664546663;11383.658490245305;11771.529138834521;11801.88198118025;8720.127987201357;8835.911640883509;8852.911470885208;8860.601272942733;8914;6096.664683442454;6299.464545513682;6314.210723659543;6332.556721029484;6333.524985626004;10807.72980675458;11091.778164436504;11341.886581134084;11345.602903898323;11389.829152562552;11274.830877536679;11370.715732106433;11410.315381077162;11416.143789215675;11439.313641181556;7622.885656715264;8159.428839981144;8320.791980200544;8359.832803344021;8459.450135741157;9251.444913304955;9273;9368.906310936803;9370.578323975302;9375.06249375057;8156.755297341089;8327;8386.329093672613;8432.36757243197;8516.105808890225;8106;8217.30152937007;8260.710875119343;8277.130901255281;8358.03882553514;7213.6753846076;7432.739854105081;7436.367908727602;7451.81370465721;7454.4781865268915;5146.6911985281;5189.896202075862;5191.584673226208;5226.137687281708;5270.7101109439345;4874.975625121851;4928.630352723489;5004.274380214922;5010.69935803853;5028.698278103326 15:17:58 INFO - PROCESS | 30864 | |4;prototype.html;331.22493365524684;335.67271909887853;344.29419689636256;345.0665948609012;346.2382757932549;5279.868003299915;5363.544098751606;5387.622866399359;5391.56867450604;5408.891177308652;5266.289050978116;5267.631265811395;5315.840524784263;5326.334208223972;5327;4085.55058943516;4211.894702632428;4216.494020717518;4231.428757117796;4249.575042495757;4135.415365396627;4161.625453709165;4225.894352641193;4233.322668373061;4246.3205887058075;4143.233501802161;4153.169366126786;4154.896127596804;4163;4174.895627609304;3983.521977362706;3995.700322475797;4076.9196163016823;4112.300908845504;4125.339156244236;3653.598104208535;3657.6708096271195;3667.9266414671556;3669.724770642186;3679.3009328227818;4897.975510122426;5043.193089105725;5078;5165.199394093921;5172.508611681884;4566.817327306937;4659.88350291242;4688.2733176357715;4710.7859306126065;4713.363695901044;5375.543078838304;5429.755660995264;5430.91853622196;5448.8365349039195;5476.67139971603;3917.5690674025836;4004.279229738646;4005;4007.31875581149;4013;5665.320161580596;5680.403557626431;5681.715914204273;5698.629589076738;5709.942900570983;4114.835406583763;4117.1678603021965;4123.319652257344;4125.525564560054;4129.628333450004;4052.777097259679;4212.978935105366;4220.388043733703;4221.767802770877;4222.1766755483;4662.673612847136;4669.4630117536235;4675.438947326343;4685.344051832746;4694.530546945346;5464.316960379952;5465.234867118606;5474.206240095164;5476.7535460904355;5481.780728770885;1417.8653027962328;1424.9715005699827;1444.9885080443732;1454.5163932788496;1469.0891647178817;926.7822061815468;943.4905151218403;945.1635302756972;952.6522819170953;961.9803008810543;4566.88582785433;4694.924824240128;4705.247160454311;4723.4331880174595;4755.714657120584;4943.456219815817;4986.177280748709;4989.850304490871;4997.650164488451;5009.423916249605;4692.249240121564;5087.728067983005;5122.513361230677;5261.736913154327;5304.761285742151;2987.5070613348553;3012.5330573761103;3014.427258820817;3019.2150040989063;3023.229076585457;5607.467290607386;5808.361080281165;5827;5862.632338907541;5865.472107510344;5737.512311453574;5740.311162660508;5764.827055188351;5796.42035796417;5803.390643982406;5718.228039214736;5795.334755521677;5886.793962211302;5898.8230353089475;5903.203067585907;5414.269073675004;5523.226748255167;5639.702868340222;5693.914591281051;5735.369109398046;5583.162525621189;5585.916211256753;5589.7143656958315;5600.747966341443;5622.662640241599;5618;5756.827295181151;5841.18223448709;5844.574501581966;5856.677882716492;4074.2258970795456;4101.118259574206;4117.75293482392;4127.33962565988;4127.545969943364;5769.740361683818;5775.556110972257;5796.130580412803;5799.898019376305;5806.635440671524;5411.918821217606;5532.2254884315425;5635.605507614428;5643.3792282848035;5659.292588426447;5559.16612508127;5565.526930210977;5572.832815015556;5592.356878958972;5649.548036157015;5491.807786727445;5492.258545096361;5497.285352904097;5511.448855114457;5549.639273447213;5291.788328466972;5628.127640215773;5632.239647647679;5652.265205523256;5685.60200785941;5436.3476382834315;5456.863578410572;5530.170474428707;5549.195366671891;5563.7496312666835 15:17:58 INFO - PROCESS | 30864 | |5;yui.html;758.7382353088186;759.244551671087;808.0908977400425;819.3731795176687;839.907610162882;14955.925220373896;15885.602859928478;15900.92049539752;15959.521214363573;16036.118013509253;16102.59743506413;16120.193990300497;16124.274407651654;16159.596010099753;16168.27242774075;681.635325101071;799.4883274704194;815.898012748408;818.2022528035178;823.8681810910256;829.2578142562403;834.6869923778584;835.2607941971369;835.5905606252923;836.0636087581917;875.689130358723;881.1188811188811;881.6561540999015;881.7971866470716;881.8765372847806;713.0836874616119;836.8200836820113;865.4504389712515;865.8960924689046;874.1389731114847;631.8862604731147;632.2728861808911;632.3929028132998;632.4181752787447;632.5034847644577;13074.215547067206;13111.737765244641;13143.27711975841;13150.73698526024;13158.07893447459;12818.423170957329;13091.345432728325;13139.737205255842;13312.667183320398;13502.257375844423;11612.129090318176;12013;12050.638480845588;12163.209391389531;12245.816312755316;3829.48301979232;3843.250566139604;3914.628110329514;3921.705872059607;3966.841326346943;59304.70347648277;59633;59724.70137649327;60176;60393;11141.108711303077;11250.268732532359;11260.605878794202;11410.771784564262;11415.60045398407;19948.50128746793;19957;20044.69932951007;20046.09792559338;20060.097295621734;8238.340932725368;8292.04641466227;8322.29260512851;8427.456109063469;8457.746267611981;4953.7523123844385;4954.677945933503;5018.422881368616;5070.923936140961;5084.898302034013;3443.741719371057;3558.252766918976;3561.893143205708;3588.964110358915;3591.4792355108884;621.3786213786213;623.7816764132517;626.7931582577829;639.133973465947;645.4320198225531;616.950643948483;715.9463040272001;722.887952367384;740.8740514112613;748.4798065344642;9798.31411801167;9838.704838854846;9904.108630223314;9921.156701680293;9954;690.2441826200303;774.3727580659571;774.6862520679022;786.6892577431883;792.7304716396337;4728.550787675165;4742.07529531738;4800;4852.587530059914;4921.089598424303;64121.35878641154;64414.355856441776;65380;65456.67271663611;68832.6558367205;58673.70663146742;59143.40856591465;59893.70053149793;60009.39990600126;61249;52929.47070529246;52972;54261.84512471388;54565.72717136389;54908.450915491136;9009.684661037094;9127.22418594427;9169.954150229207;9237.445753254826;9361.204297634777;36675.816620917794;36966.26067478689;37006.25987480181;37070.8146459266;37271.6272837279;45673.55922559812;49250.75374623104;49654;50289;50290.994180115464;11818.763624727286;11937.582184623496;12006.939965300118;12127.514899404277;12361.876381236072;12854;13068.607941761762;13370.665733356744;13399.33003349809;13513.729725405634;9919.603215871284;12968.610941671388;13285.257527615351;13381.330933453093;13523.120997135155;13219.40512676951;13447.327633618084;13584.592462226148;13845.376958037114;13892.652683682989;13401.463941442233;13509.59471215865;13518.324083795575;14280.143191408548;14280.357383917957;10852.240343175901;11025.117990560575;11069.114470842153;11069.417449047429;11388.316700997966;11435.256708313933;11526.538938442369;11818;11836.171467997157;11945.283283003047 15:17:58 INFO - PROCESS | 30864 | __end_tp_report 15:17:58 INFO - PROCESS | 30864 | __start_cc_report 15:17:58 INFO - PROCESS | 30864 | _x_x_mozilla_cycle_collect,807 15:17:58 INFO - PROCESS | 30864 | __end_cc_report 15:17:58 INFO - PROCESS | 30864 | __startTimestamp1464733078591__endTimestamp 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | ------- Summary: start ------- 15:17:58 INFO - PROCESS | 30864 | Number of tests: 6 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#0] dojo.html Cycles:165 Average:5422.93 Median:5624.52 stddev:617.19 (11.0%) stddev-sans-first:592.66 15:17:58 INFO - PROCESS | 30864 | Values: 3145.3 3159.8 3177.8 3203.2 3228.9 5326.2 5393.3 5456.1 5530.7 5593.6 5437.9 5466.7 5490.4 5556.2 5558.1 4906.9 4968.5 4975.8 4991.0 5082.7 5012.6 5051.5 5066.2 5087.9 5115.1 4829.5 4870.0 4882.2 4891.8 4962.0 4935.9 4941.7 4961.7 4967.5 5008.5 4964.0 4980.9 4990.6 5002.3 5030.4 5289.5 5374.1 5441.2 5447.9 5500.6 5846.6 5893.3 5900.9 5903.4 5904.5 5896.5 5905.5 5938.7 5953.4 5953.6 5259.8 5419.9 5490.9 5497.5 5550.5 6031.6 6059.3 6084.4 6085.7 6086.0 5507.9 5777.4 5818.1 5824.9 5834.6 5660.1 5730.8 5733.8 5741.5 5809.2 5707.8 5709.2 5715.3 5719.4 5725.7 5575.1 5688.8 5754.7 5756.1 5783.2 4441.4 4453.8 4454.1 4492.2 4496.6 3958.2 3967.7 3976.3 3982.8 4002.0 5309.0 5363.4 5385.8 5488.4 5494.2 5814.0 5844.2 5869.9 5872.0 5931.1 5440.3 5450.7 5454.9 5457.9 5459.5 4930.8 5010.2 5028.9 5035.5 5074.1 6113.4 6120.5 6122.4 6126.6 6127.1 5894.4 5925.8 5928.8 5930.8 5980.3 5818.4 6017.5 6055.9 6062.4 6071.9 5725.0 5754.1 5758.1 5769.9 5772.3 5870.1 5889.6 5891.1 5892.7 5896.8 5542.3 5638.2 5675.7 5679.2 5709.9 5691.1 5698.3 5701.9 5706.5 5710.7 5479.2 5612.5 5615.0 5621.9 5624.5 5685.5 5732.8 5753.9 5823.0 5825.7 5650.7 5651.0 5652.5 5659.3 5695.2 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#1] ext.html Cycles:180 Average:56269.59 Median:42027.68 stddev:105559.62 (251.2%) stddev-sans-first:105773.03 15:17:58 INFO - PROCESS | 30864 | Values: 613.5 629.1 644.7 647.8 648.5 31139.5 34232.0 34285.8 42362.6 43554.1 45817.0 46327.3 46408.0 46409.3 46431.8 8638.2 8797.6 8802.9 8818.6 8822.9 8316.9 8702.6 8703.3 8710.5 8711.5 8748.4 8766.9 8767.2 8767.7 8773.3 8684.2 8757.6 8760.3 8762.3 8762.7 8661.5 8729.5 8736.0 8736.6 8739.3 42090.6 42567.6 42611.4 42654.8 42685.6 3413.5 3623.8 3730.9 3978.9 4092.5 31787.5 32195.0 32298.4 32574.2 32835.8 4372.4 4405.4 4433.1 4463.5 4528.2 256328.0 257017.0 257447.0 257556.0 257765.0 53751.2 61143.4 61215.7 61581.7 61599.7 62988.7 64437.0 64657.7 69586.7 69778.0 15662.9 15869.9 15875.7 15903.0 15911.7 10979.3 11341.7 11488.1 11609.8 11890.7 18669.3 18716.7 18797.8 18800.2 18833.5 6012.9 6041.0 6150.4 6200.1 6213.9 1036.7 1047.3 1133.7 1140.0 1157.2 32444.5 32816.2 32919.5 32930.0 33426.5 1158.4 1161.2 1191.9 1209.5 1218.5 16958.3 17328.2 17341.9 17345.9 17425.2 498046.0 498539.0 498836.0 499226.0 499867.0 59340.7 59700.4 59765.0 59800.4 59805.0 1269.3 1271.5 1341.8 1349.6 1380.1 32066.7 32834.8 32847.3 33396.2 33928.7 51222.0 51446.7 51712.2 51750.7 51808.5 400538.0 402050.0 403455.0 403476.0 403516.0 36957.8 41500.4 42379.4 42774.0 45653.3 49704.3 49945.0 49968.0 50259.5 51335.5 41648.2 42145.4 42205.2 42938.1 43441.0 40604.0 41744.4 41879.8 41938.6 41964.8 38300.6 38572.6 38916.0 38939.6 40310.8 34824.0 35884.8 36129.6 36293.6 36585.6 28378.3 28585.4 28586.7 28798.3 28962.0 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#2] jquery.html Cycles:180 Average:29325.20 Median:2429.91 stddev:81015.76 (3334.1%) stddev-sans-first:81213.91 15:17:58 INFO - PROCESS | 30864 | Values: 395.7 398.7 402.9 408.5 417.2 18097.5 19600.7 19615.0 19784.5 20557.0 19312.0 19565.6 19873.6 20091.8 20124.5 5578.6 5633.6 5788.1 5815.5 5829.5 5719.1 5720.2 5751.0 5779.6 5789.1 5526.1 5684.0 5715.8 5730.9 5733.7 5502.2 5640.0 5651.5 5694.1 5696.8 5150.7 5156.5 5167.2 5177.7 5178.2 22809.2 22850.7 23241.9 23294.8 23359.0 2350.2 2355.7 2396.6 2399.2 2460.7 7418.4 9160.6 14332.5 14412.4 14975.2 1968.1 2031.8 2037.5 2055.5 2070.3 79822.2 79996.0 80386.6 80444.2 82623.0 10479.6 10626.4 10968.3 10969.4 11048.3 14977.3 15154.8 15155.2 15156.9 15164.5 7747.8 7875.7 7959.9 8059.6 8125.6 7581.2 7622.7 7623.4 7711.1 7765.1 6753.8 6774.6 6808.4 6820.7 6855.4 1869.8 1890.2 1912.7 1936.5 1965.6 867.7 950.5 981.8 1034.8 1057.8 18704.9 19749.8 20018.8 20054.2 20375.8 893.3 950.7 999.8 1051.2 1092.8 10864.6 11544.7 11564.6 11565.2 11588.5 471656.0 488229.0 494133.0 495707.0 502675.0 96008.5 98676.5 99536.5 99768.5 100307.5 1007.2 1013.9 1035.1 1058.6 1059.4 17276.7 17687.2 17970.3 18184.1 18200.8 40881.0 41313.6 42336.8 42891.1 43142.4 56483.0 57597.0 58096.0 58453.4 59141.0 8373.6 8531.8 8607.8 8626.0 8639.2 9590.9 12928.0 12948.5 13013.3 13447.6 11220.6 11236.0 11642.9 11801.1 12080.4 11074.2 11240.5 11422.7 11479.0 11556.4 11421.9 11695.4 11781.0 12059.6 12060.3 7397.0 7569.9 8535.2 8680.3 8715.8 11711.6 11906.7 12096.6 12368.3 12570.1 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#3] mootools.html Cycles:180 Average:6052.21 Median:2623.40 stddev:3615.21 (137.8%) stddev-sans-first:3608.06 15:17:58 INFO - PROCESS | 30864 | Values: 1346.7 1515.7 1542.2 1669.6 1819.4 8475.1 8798.0 8886.0 9130.6 9135.9 8903.4 9088.8 9111.0 9123.0 9128.7 2336.1 2354.5 2356.6 2360.7 2361.6 2402.7 2432.1 2445.4 2448.8 2453.4 2324.8 2345.0 2355.2 2379.3 2383.4 2209.9 2213.2 2227.8 2228.9 2292.2 2272.6 2275.8 2278.1 2279.1 2279.3 8947.2 9000.2 9001.7 9011.5 9247.6 5281.3 5386.8 5391.6 5394.1 5404.3 3340.5 3376.7 3417.1 3430.2 3433.8 1076.5 1122.8 1142.8 1155.5 1159.6 13505.9 13599.2 13632.3 13647.0 13657.0 4623.0 4655.4 4756.0 4757.0 4768.1 10472.0 10503.6 10524.9 10532.9 10540.5 5208.3 5255.2 5258.5 5269.9 5270.2 4371.5 4785.9 4934.5 4976.4 4982.4 3938.8 3977.7 4001.6 4001.6 4031.1 1151.0 1174.1 1174.8 1180.3 1187.6 747.3 918.7 925.3 951.6 952.6 5751.4 5772.2 5862.0 5883.4 5923.4 478.7 491.4 496.6 497.5 656.4 3919.8 4135.3 4146.6 4149.8 4173.0 11113.8 11458.5 11479.5 11535.9 11571.3 11174.1 11366.8 11383.7 11771.5 11801.9 8720.1 8835.9 8852.9 8860.6 8914.0 6096.7 6299.5 6314.2 6332.6 6333.5 10807.7 11091.8 11341.9 11345.6 11389.8 11274.8 11370.7 11410.3 11416.1 11439.3 7622.9 8159.4 8320.8 8359.8 8459.5 9251.4 9273.0 9368.9 9370.6 9375.1 8156.8 8327.0 8386.3 8432.4 8516.1 8106.0 8217.3 8260.7 8277.1 8358.0 7213.7 7432.7 7436.4 7451.8 7454.5 5146.7 5189.9 5191.6 5226.1 5270.7 4875.0 4928.6 5004.3 5010.7 5028.7 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#4] prototype.html Cycles:180 Average:4633.47 Median:5273.75 stddev:1345.63 (25.5%) stddev-sans-first:1310.08 15:17:58 INFO - PROCESS | 30864 | Values: 331.2 335.7 344.3 345.1 346.2 5279.9 5363.5 5387.6 5391.6 5408.9 5266.3 5267.6 5315.8 5326.3 5327.0 4085.6 4211.9 4216.5 4231.4 4249.6 4135.4 4161.6 4225.9 4233.3 4246.3 4143.2 4153.2 4154.9 4163.0 4174.9 3983.5 3995.7 4076.9 4112.3 4125.3 3653.6 3657.7 3667.9 3669.7 3679.3 4898.0 5043.2 5078.0 5165.2 5172.5 4566.8 4659.9 4688.3 4710.8 4713.4 5375.5 5429.8 5430.9 5448.8 5476.7 3917.6 4004.3 4005.0 4007.3 4013.0 5665.3 5680.4 5681.7 5698.6 5709.9 4114.8 4117.2 4123.3 4125.5 4129.6 4052.8 4213.0 4220.4 4221.8 4222.2 4662.7 4669.5 4675.4 4685.3 4694.5 5464.3 5465.2 5474.2 5476.8 5481.8 1417.9 1425.0 1445.0 1454.5 1469.1 926.8 943.5 945.2 952.7 962.0 4566.9 4694.9 4705.2 4723.4 4755.7 4943.5 4986.2 4989.9 4997.7 5009.4 4692.2 5087.7 5122.5 5261.7 5304.8 2987.5 3012.5 3014.4 3019.2 3023.2 5607.5 5808.4 5827.0 5862.6 5865.5 5737.5 5740.3 5764.8 5796.4 5803.4 5718.2 5795.3 5886.8 5898.8 5903.2 5414.3 5523.2 5639.7 5693.9 5735.4 5583.2 5585.9 5589.7 5600.7 5622.7 5618.0 5756.8 5841.2 5844.6 5856.7 4074.2 4101.1 4117.8 4127.3 4127.5 5769.7 5775.6 5796.1 5799.9 5806.6 5411.9 5532.2 5635.6 5643.4 5659.3 5559.2 5565.5 5572.8 5592.4 5649.5 5491.8 5492.3 5497.3 5511.4 5549.6 5291.8 5628.1 5632.2 5652.3 5685.6 5436.3 5456.9 5530.2 5549.2 5563.7 15:17:58 INFO - PROCESS | 30864 | 15:17:58 INFO - PROCESS | 30864 | [#5] yui.html Cycles:180 Average:15733.06 Median:27102.25 stddev:18475.64 (68.2%) stddev-sans-first:18493.25 15:17:58 INFO - PROCESS | 30864 | Values: 758.7 759.2 808.1 819.4 839.9 14955.9 15885.6 15900.9 15959.5 16036.1 16102.6 16120.2 16124.3 16159.6 16168.3 681.6 799.5 815.9 818.2 823.9 829.3 834.7 835.3 835.6 836.1 875.7 881.1 881.7 881.8 881.9 713.1 836.8 865.5 865.9 874.1 631.9 632.3 632.4 632.4 632.5 13074.2 13111.7 13143.3 13150.7 13158.1 12818.4 13091.3 13139.7 13312.7 13502.3 11612.1 12013.0 12050.6 12163.2 12245.8 3829.5 3843.3 3914.6 3921.7 3966.8 59304.7 59633.0 59724.7 60176.0 60393.0 11141.1 11250.3 11260.6 11410.8 11415.6 19948.5 19957.0 20044.7 20046.1 20060.1 8238.3 8292.0 8322.3 8427.5 8457.7 4953.8 4954.7 5018.4 5070.9 5084.9 3443.7 3558.3 3561.9 3589.0 3591.5 621.4 623.8 626.8 639.1 645.4 617.0 715.9 722.9 740.9 748.5 9798.3 9838.7 9904.1 9921.2 9954.0 690.2 774.4 774.7 786.7 792.7 4728.6 4742.1 4800.0 4852.6 4921.1 64121.4 64414.4 65380.0 65456.7 68832.7 58673.7 59143.4 59893.7 60009.4 61249.0 52929.5 52972.0 54261.8 54565.7 54908.5 9009.7 9127.2 9170.0 9237.4 9361.2 36675.8 36966.3 37006.3 37070.8 37271.6 45673.6 49250.8 49654.0 50289.0 50291.0 11818.8 11937.6 12006.9 12127.5 12361.9 12854.0 13068.6 13370.7 13399.3 13513.7 9919.6 12968.6 13285.3 13381.3 13523.1 13219.4 13447.3 13584.6 13845.4 13892.7 13401.5 13509.6 13518.3 14280.1 14280.4 10852.2 11025.1 11069.1 11069.4 11388.3 11435.3 11526.5 11818.0 11836.2 11945.3 15:17:58 INFO - PROCESS | 30864 | -------- Summary: end -------- 15:17:58 INFO - PROCESS | 30864 | 15:17:59 INFO - TEST-INFO | 30864: exit 0 15:17:59 INFO - TEST-OK | dromaeo_css | took 1112702ms 15:17:59 INFO - TEST-START | kraken 15:17:59 INFO - operating with platform_type : linux_ 15:17:59 INFO - Initialising browser for kraken test... 15:17:59 INFO - TEST-INFO | started process 31053 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMqyBvz/profile http://localhost:55710/getInfo.html) 15:18:03 INFO - PROCESS | 31053 | __metrics Screen width/height:1600/1200 15:18:03 INFO - PROCESS | 31053 | colorDepth:24 15:18:03 INFO - PROCESS | 31053 | Browser inner width/height: 1024/697 15:18:03 INFO - PROCESS | 31053 | __metrics 15:18:04 ERROR - PROCESS | 31053 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:18:05 INFO - TEST-INFO | 31053: exit 0 15:18:05 INFO - Browser initialized. 15:18:05 INFO - Running cycle 1/1 for kraken test... 15:18:05 INFO - TEST-INFO | started process 31135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMqyBvz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:18:17 INFO - PROCESS | 31135 | RSS: Main: 156086272 15:18:17 INFO - PROCESS | 31135 | 15:18:19 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55710/tests/kraken/driver.html?testName=audio-beat-detection) 15:18:20 INFO - PROCESS | 31135 | RSS: Main: 215052288 15:18:20 INFO - PROCESS | 31135 | 15:18:23 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55710/tests/kraken/driver.html?testName=audio-dft) 15:18:23 INFO - PROCESS | 31135 | RSS: Main: 202653696 15:18:23 INFO - PROCESS | 31135 | 15:18:27 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55710/tests/kraken/driver.html?testName=audio-fft) 15:18:27 INFO - PROCESS | 31135 | RSS: Main: 268595200 15:18:27 INFO - PROCESS | 31135 | 15:18:29 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55710/tests/kraken/driver.html?testName=audio-oscillator) 15:18:30 INFO - PROCESS | 31135 | RSS: Main: 179777536 15:18:30 INFO - PROCESS | 31135 | 15:18:32 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55710/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:18:32 INFO - PROCESS | 31135 | RSS: Main: 184201216 15:18:32 INFO - PROCESS | 31135 | 15:18:36 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55710/tests/kraken/driver.html?testName=imaging-darkroom) 15:18:36 INFO - PROCESS | 31135 | RSS: Main: 218116096 15:18:36 INFO - PROCESS | 31135 | 15:18:40 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55710/tests/kraken/driver.html?testName=imaging-desaturate) 15:18:40 INFO - PROCESS | 31135 | RSS: Main: 220221440 15:18:40 INFO - PROCESS | 31135 | 15:18:43 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55710/tests/kraken/driver.html?testName=json-parse-financial) 15:18:44 INFO - PROCESS | 31135 | RSS: Main: 220254208 15:18:44 INFO - PROCESS | 31135 | 15:18:45 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55710/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:18:46 INFO - PROCESS | 31135 | RSS: Main: 206811136 15:18:46 INFO - PROCESS | 31135 | 15:18:48 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:18:48 INFO - PROCESS | 31135 | RSS: Main: 209707008 15:18:48 INFO - PROCESS | 31135 | 15:18:50 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:18:51 INFO - PROCESS | 31135 | RSS: Main: 216432640 15:18:51 INFO - PROCESS | 31135 | 15:18:53 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:18:54 INFO - PROCESS | 31135 | RSS: Main: 222683136 15:18:54 INFO - PROCESS | 31135 | 15:18:57 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:18:57 INFO - PROCESS | 31135 | RSS: Main: 223531008 15:18:57 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | Cycle 1(1): loaded http://localhost:55710/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55710/tests/kraken/driver.html?testName=ai-astar) 15:18:59 INFO - PROCESS | 31135 | RSS: Main: 266297344 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | __start_tp_report 15:18:59 INFO - PROCESS | 31135 | _x_x_mozilla_page_load 15:18:59 INFO - PROCESS | 31135 | _x_x_mozilla_page_load_details 15:18:59 INFO - PROCESS | 31135 | |i|pagename|runs| 15:18:59 INFO - PROCESS | 31135 | |0;ai-astar;110;106;109;109;110;109;109;109;110;110 15:18:59 INFO - PROCESS | 31135 | |1;audio-beat-detection;159;126;126;185;127;126;147;124;158;124 15:18:59 INFO - PROCESS | 31135 | |2;audio-dft;162;161;161;160;160;161;161;161;161;160 15:18:59 INFO - PROCESS | 31135 | |3;audio-fft;74;75;75;115;75;75;74;74;96;75 15:18:59 INFO - PROCESS | 31135 | |4;audio-oscillator;83;83;111;82;83;82;122;82;84;133 15:18:59 INFO - PROCESS | 31135 | |5;imaging-gaussian-blur;117;116;117;117;116;116;116;117;117;117 15:18:59 INFO - PROCESS | 31135 | |6;imaging-darkroom;147;146;145;146;146;146;146;147;145;146 15:18:59 INFO - PROCESS | 31135 | |7;imaging-desaturate;95;95;97;95;96;95;95;96;95;95 15:18:59 INFO - PROCESS | 31135 | |8;json-parse-financial;53;54;53;54;54;54;54;54;54;54 15:18:59 INFO - PROCESS | 31135 | |9;json-stringify-tinderbox;61;62;61;62;62;66;63;61;62;62 15:18:59 INFO - PROCESS | 31135 | |10;stanford-crypto-aes;85;78;78;80;80;86;84;80;126;79 15:18:59 INFO - PROCESS | 31135 | |11;stanford-crypto-ccm;135;120;119;131;118;118;127;121;138;122 15:18:59 INFO - PROCESS | 31135 | |12;stanford-crypto-pbkdf2;166;160;163;159;168;162;157;159;163;158 15:18:59 INFO - PROCESS | 31135 | |13;stanford-crypto-sha256-iterative;62;63;63;63;63;63;65;62;62;60 15:18:59 INFO - PROCESS | 31135 | __end_tp_report 15:18:59 INFO - PROCESS | 31135 | __start_cc_report 15:18:59 INFO - PROCESS | 31135 | _x_x_mozilla_cycle_collect,1568 15:18:59 INFO - PROCESS | 31135 | __end_cc_report 15:18:59 INFO - PROCESS | 31135 | __startTimestamp1464733139767__endTimestamp 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | ------- Summary: start ------- 15:18:59 INFO - PROCESS | 31135 | Number of tests: 14 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:1.20 (1.1%) stddev-sans-first:1.22 15:18:59 INFO - PROCESS | 31135 | Values: 110.0 106.0 109.0 109.0 110.0 109.0 109.0 109.0 110.0 110.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:137.00 stddev:21.15 (15.4%) stddev-sans-first:21.32 15:18:59 INFO - PROCESS | 31135 | Values: 159.0 126.0 126.0 185.0 127.0 126.0 147.0 124.0 158.0 124.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 15:18:59 INFO - PROCESS | 31135 | Values: 162.0 161.0 161.0 160.0 160.0 161.0 161.0 161.0 161.0 160.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:13.77 (18.4%) stddev-sans-first:14.39 15:18:59 INFO - PROCESS | 31135 | Values: 74.0 75.0 75.0 115.0 75.0 75.0 74.0 74.0 96.0 75.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:82.50 stddev:19.68 (23.9%) stddev-sans-first:20.43 15:18:59 INFO - PROCESS | 31135 | Values: 83.0 83.0 111.0 82.0 83.0 82.0 122.0 82.0 84.0 133.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:18:59 INFO - PROCESS | 31135 | Values: 117.0 116.0 117.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 15:18:59 INFO - PROCESS | 31135 | Values: 147.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 145.0 146.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 15:18:59 INFO - PROCESS | 31135 | Values: 95.0 95.0 97.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.33 15:18:59 INFO - PROCESS | 31135 | Values: 53.0 54.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:1.48 (2.4%) stddev-sans-first:1.50 15:18:59 INFO - PROCESS | 31135 | Values: 61.0 62.0 61.0 62.0 62.0 66.0 63.0 61.0 62.0 62.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:80.00 stddev:14.49 (18.1%) stddev-sans-first:15.36 15:18:59 INFO - PROCESS | 31135 | Values: 85.0 78.0 78.0 80.0 80.0 86.0 84.0 80.0 126.0 79.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:124.50 stddev:7.40 (5.9%) stddev-sans-first:6.89 15:18:59 INFO - PROCESS | 31135 | Values: 135.0 120.0 119.0 131.0 118.0 118.0 127.0 121.0 138.0 122.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.50 stddev:3.57 (2.2%) stddev-sans-first:3.39 15:18:59 INFO - PROCESS | 31135 | Values: 166.0 160.0 163.0 159.0 168.0 162.0 157.0 159.0 163.0 158.0 15:18:59 INFO - PROCESS | 31135 | 15:18:59 INFO - PROCESS | 31135 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:63.00 stddev:1.26 (2.0%) stddev-sans-first:1.32 15:18:59 INFO - PROCESS | 31135 | Values: 62.0 63.0 63.0 63.0 63.0 63.0 65.0 62.0 62.0 60.0 15:18:59 INFO - PROCESS | 31135 | -------- Summary: end -------- 15:18:59 INFO - PROCESS | 31135 | 15:19:00 INFO - TEST-INFO | 31135: exit 0 15:19:00 INFO - TEST-OK | kraken | took 61405ms 15:19:00 INFO - SUITE-END | took 1174s 15:19:00 INFO - Completed test suite (00:19:34) 15:19:00 INFO - javascript benchmark 15:19:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.2923092304227, 3159.7630177736673, 3177.809331440113, 3203.1671765341002, 3228.9192770180744, 5326.227696983935, 5393.2719082923895, 5456.127019676853, 5530.668159910403, 5593.580481463896, 5437.864053398667, 5466.726663666821, 5490.423505531921, 5556.249906262653, 5558.110702287635, 4906.901861962768, 4968.503149685039, 4975.751212439363, 4990.951900100965, 5082.695038297714, 5012.573931215851, 5051.5453609175165, 5066.18940969445, 5087.872803179913, 5115.079285728586, 4829.468758436569, 4870.025994801036, 4882.194437917739, 4891.75541222941, 4962.0, 4935.925961110586, 4941.654084214107, 4961.727105009223, 4967.478414766434, 5008.474110218411, 4964.007198560266, 4980.9252861207115, 4990.6257030722845, 5002.349694539723, 5030.421501527335, 5289.497497737709, 5374.059539580558, 5441.183822426668, 5447.918281225785, 5500.614956953015, 5846.619969701956, 5893.263342082239, 5900.940990590082, 5903.439173278532, 5904.498117660004, 5896.469317761422, 5905.527557795409, 5938.703064846741, 5953.404659534055, 5953.553483488712, 5259.789608415659, 5419.945800541984, 5490.862728431781, 5497.532709719679, 5550.528205102531, 6031.616866298154, 6059.333473317932, 6084.421979911901, 6085.665288409092, 6086.0, 5507.9449205507435, 5777.422257774189, 5818.069108942549, 5824.854378640483, 5834.591578589458, 5660.094384898396, 5730.770769169187, 5733.77064917407, 5741.483266506035, 5809.186713860063, 5707.800226992035, 5709.200711900337, 5715.342735585377, 5719.399463056402, 5725.656460612377, 5575.080111781511, 5688.82933511987, 5754.74103665336, 5756.107803290497, 5783.248177736868, 4441.400410944546, 4453.799579018952, 4454.086912182995, 4492.191405547033, 4496.6177874881005, 3958.2281455116554, 3967.7420967637013, 3976.304146774303, 3982.78094704794, 4002.0195052212393, 5308.973455132699, 5363.410024897258, 5385.838424847261, 5488.423715509894, 5494.1758736189095, 5814.040683287295, 5844.211031510777, 5869.911951320733, 5871.970640146772, 5931.11033344993, 5440.347158341025, 5450.727463626882, 5454.863628409322, 5457.890842183214, 5459.454054594509, 4930.753462326869, 5010.198368261061, 5028.949710502921, 5035.521625445577, 5074.06129865976, 6113.354685976905, 6120.508083830883, 6122.428649698624, 6126.639818174636, 6127.128444391265, 5894.440028197314, 5925.762969481172, 5928.822135335947, 5930.762769489172, 5980.342162362137, 5818.359980402068, 6017.548683848641, 6055.9091613626715, 6062.42406971337, 6071.908921366095, 5725.0, 5754.136879468113, 5758.078707406795, 5769.91345129815, 5772.336181339199, 5870.119482077723, 5889.587728858939, 5891.05743081105, 5892.6759028252145, 5896.7936122235515, 5542.2795036644975, 5638.182463542846, 5675.7445914933105, 5679.204911312337, 5709.942900570941, 5691.117876729114, 5698.31620205578, 5701.942980570141, 5706.457886500776, 5710.714464276686, 5479.150731636602, 5612.494875461228, 5615.0, 5621.943780562142, 5624.521915637217, 5685.459881311269, 5732.828015159552, 5753.8561535961935, 5823.039198532195, 5825.70871456434, 5650.689212093374, 5651.0, 5652.463016013472, 5659.349174844946, 5695.174199741097], "value": 5381.446835545241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.5306490534741, 629.0721186250281, 644.7066584703961, 647.8153726188037, 648.4650163615019, 31139.532907006385, 34231.99999999997, 34285.82857085714, 42362.57637423625, 43554.12891742163, 45817.0, 46327.30509042367, 46408.0, 46409.30386044212, 46431.76784116083, 8638.179372959574, 8797.604107815132, 8802.86795698065, 8818.64725410986, 8822.86765698515, 8316.875246871301, 8702.608382622797, 8703.260222881032, 8710.520921349324, 8711.520866352348, 8748.387612867102, 8766.912330876674, 8767.167119123706, 8767.736967890942, 8773.25427338677, 8684.218420342168, 8757.56212189388, 8760.255378292853, 8762.34282428814, 8762.737117886441, 8661.480311181349, 8729.519876406795, 8736.039035706068, 8736.60685269158, 8739.344549158775, 42090.57909420928, 42567.57432425698, 42611.36082958758, 42654.78672606617, 42685.57314426847, 3413.4709120086427, 3623.8188090595363, 3730.9253814923823, 3978.900527486836, 4092.4884389451317, 31787.523187152212, 32195.0, 32298.354032919444, 32574.185645359055, 32835.83582082098, 4372.409724687158, 4405.405270288502, 4433.13553856998, 4463.486699029589, 4528.18492671319, 256328.0, 257017.0, 257447.0, 257556.0, 257765.0, 53751.19373209405, 61143.388566114656, 61215.69392153011, 61581.692091539255, 61599.692001539705, 62988.685056574424, 64437.0, 64657.67671161708, 69586.65206673935, 69778.0, 15662.9216853915, 15869.920650396905, 15875.682486350208, 15903.045817251003, 15911.68176636484, 10979.28634638746, 11341.716457088474, 11488.138389620812, 11609.76780464386, 11890.702732431759, 18669.346572869883, 18716.719249211274, 18797.81202187988, 18800.247990080516, 18833.52916177079, 6012.896909636722, 6040.969795150996, 6150.415710507495, 6200.100985357189, 6213.906791398133, 1036.7356324137447, 1047.3297089862494, 1133.6849254864483, 1139.9601013964636, 1157.1552766480515, 32444.513332300034, 32816.1795955103, 32919.50620740691, 32930.0, 33426.49860252098, 1158.3744777820052, 1161.2045748662194, 1191.8677256606259, 1209.5343292832147, 1218.5491368193825, 16958.321667133176, 17328.220230089675, 17341.929555590017, 17345.913270433568, 17425.215865286093, 498046.0, 498539.0, 498836.0, 499226.0, 499867.0, 59340.70329648324, 59700.40299596948, 59765.0, 59800.401995980355, 59805.0, 1269.2511418263084, 1271.5422447918743, 1341.8188544546556, 1349.5748839115647, 1380.061558140454, 32066.679333206368, 32834.835825820715, 32847.34305313928, 33396.1650958728, 33928.66071339255, 51222.0, 51446.742766285926, 51712.224316636035, 51750.741246293524, 51808.48191518037, 400538.0, 402050.0, 403455.0, 403476.0, 403516.0, 36957.81521092377, 41500.377494338216, 42379.364309535995, 42774.0, 45653.31520027136, 49704.25443618276, 49945.0, 49968.0, 50259.497405026945, 51335.48664513307, 41648.1670366597, 42145.36781948212, 42205.155896881275, 42938.14123717446, 43441.0, 40603.98490037773, 41744.424328034154, 41879.79060104802, 41938.580614193466, 41964.790176048926, 38300.61699383082, 38572.6142738569, 38916.0, 38939.61060389473, 40310.79844600758, 34824.0, 35884.820575896956, 36129.638703612625, 36293.63706362902, 36585.63414365822, 28378.2905427366, 28585.42829143447, 28586.714132858404, 28798.280042999093, 28962.0], "value": 20097.575453688227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.7111308744618, 398.65317174058566, 402.9375446805746, 408.47918903398164, 417.20314199878237, 18097.547561310974, 19600.70598941017, 19615.0, 19784.505387365287, 20557.01923221132, 19312.0, 19565.608687826272, 19873.60252794943, 20091.799082009176, 20124.496887577818, 5578.63738856975, 5633.605647604659, 5788.131780232978, 5815.505682017034, 5829.504492118175, 5719.056355701305, 5720.227769251161, 5751.0, 5779.595428320019, 5789.102689083199, 5526.143447765603, 5684.033714268564, 5715.828525144252, 5730.914036289459, 5733.713314334287, 5502.174673798922, 5640.012997725402, 5651.547876169897, 5694.145878118274, 5696.829095127174, 5150.716710580917, 5156.510131537498, 5167.199084141964, 5177.663451875654, 5178.17149256121, 22809.201677941193, 22850.657240141412, 23241.88379058111, 23294.7670523296, 23359.0, 2350.247920665388, 2355.6702061711376, 2396.5686176488225, 2399.1722855614785, 2460.6555082288314, 7418.406527477789, 9160.633574657006, 14332.49836255726, 14412.351444185037, 14975.176365299903, 1968.1143485431612, 2031.7561892572985, 2037.5313677854176, 2055.4553043443498, 2070.27540360874, 79822.20177798206, 79996.0, 80386.5980670093, 80444.19555804426, 82623.0, 10479.633212837514, 10626.415547144827, 10968.287061340987, 10969.396683182342, 11048.337099774039, 14977.326020329112, 15154.848451515565, 15155.181378234462, 15156.924215379073, 15164.545063648107, 7747.767566973, 7875.684972601031, 7959.920400795918, 8059.622411388655, 8125.634346454423, 7581.166071732105, 7622.719120883515, 7623.428242881695, 7711.149324162161, 7765.107012693613, 6753.797386078426, 6774.559653622498, 6808.4212841908, 6820.727170913207, 6855.417289530346, 1869.7662792150982, 1890.1683259365834, 1912.6844070728173, 1936.5061909213061, 1965.626530959113, 867.6746220167437, 950.4867371619387, 981.8036392721484, 1034.818906691326, 1057.772578895541, 18704.906475467535, 19749.802501974795, 20018.799812001984, 20054.197832086844, 20375.796242037686, 893.3389291923929, 950.6862735297335, 999.8000399920045, 1051.2273478992934, 1092.808758467265, 10864.619738309122, 11544.711382215512, 11564.595239166589, 11565.19043666952, 11588.478518466689, 471656.0, 488229.0, 494133.0, 495707.0, 502675.0, 96008.51995740116, 98676.5066174679, 99536.5023174894, 99768.5011574952, 100307.49846250722, 1007.1640538353149, 1013.9442330671884, 1035.135661722455, 1058.6030238660503, 1059.4490864750287, 17276.74084888753, 17687.204075816877, 17970.281188752302, 18184.09079545991, 18200.81799182044, 40881.0, 41313.58686413218, 42336.78831605946, 42891.142177155656, 43142.35286470768, 56483.0, 57597.0, 58096.0, 58453.415465844795, 59141.0, 8373.581320933807, 8531.786705332417, 8607.82784344297, 8625.956870215608, 8639.179277968582, 9590.944996050568, 12928.0, 12948.546800861925, 13013.284269365276, 13447.596572102853, 11220.607278745205, 11236.0, 11642.94178529102, 11801.114916381133, 12080.39598020113, 11074.169437292074, 11240.494177762184, 11422.657320280405, 11479.0, 11556.42217889119, 11421.942890285496, 11695.356755378196, 11781.0, 12059.638210853689, 12060.276383417024, 7397.0, 7569.886451703119, 8535.189157030072, 8680.262177714965, 8715.782105447415, 11711.590094346657, 11906.702332441759, 12096.637100886988, 12368.319742414255, 12570.1200915935], "value": 9369.664941372117, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.7373862096883, 1515.7120147172036, 1542.1980570103547, 1669.607642204082, 1819.4268805326321, 8475.110113438093, 8797.956010219948, 8886.0, 9130.634774609007, 9135.862962055558, 8903.37676362655, 9088.772780680487, 9111.0, 9123.0, 9128.680496182635, 2336.135629816966, 2354.5290941811622, 2356.587597170497, 2360.7403185649564, 2361.645753137026, 2402.711674599042, 2432.1001229545004, 2445.40087678519, 2448.7796098351146, 2453.3989172652723, 2324.848884822481, 2344.9765502345012, 2355.1639168095335, 2379.286214135761, 2383.4160630645515, 2209.9005544750526, 2213.18112298449, 2227.8217742580637, 2228.944276393095, 2292.197730794225, 2272.579572779041, 2275.8179345652306, 2278.054607337953, 2279.1453205048106, 2279.282026161754, 8947.150020748019, 9000.189982901506, 9001.729948101567, 9011.504367259799, 9247.583858726306, 5281.260623512711, 5386.78452861885, 5391.595630327741, 5394.109971854639, 5404.297441332641, 3340.515625234352, 3376.7129793967542, 3417.0603084151808, 3430.1939044324563, 3433.8454769535433, 1076.4832880217461, 1122.8428020077197, 1142.8285757136414, 1155.4684844971323, 1159.6463078760974, 13505.864941350657, 13599.184048956995, 13632.250226237553, 13647.0, 13657.0, 4623.029163875623, 4655.394798676218, 4755.952440475551, 4756.9524304756505, 4768.094062128184, 10472.0, 10503.579856805794, 10524.947375263075, 10532.947335263274, 10540.525676344583, 5208.296879921238, 5255.185446255837, 5258.500442458037, 5269.868253293622, 5270.183121616155, 4371.519132895379, 4785.9042819144115, 4934.506549345037, 4976.377952755905, 4982.3522942018035, 3938.7636741795586, 3977.7215594908657, 4001.599840015975, 4001.619846114673, 4031.133306339151, 1150.9597164099216, 1174.0842143128373, 1174.812030075201, 1180.2800291821982, 1187.619961612293, 747.312472525278, 918.7427520294194, 925.2967744514218, 951.5908159491347, 952.5523004188159, 5751.424857514216, 5772.191893134965, 5861.970690146607, 5883.411658834168, 5923.407659234128, 478.7127723366022, 491.44466752569366, 496.6027178257452, 497.53729032000587, 656.4354654996699, 3919.784411857318, 4135.255653982252, 4146.606072423115, 4149.834006639761, 4173.0, 11113.777724445466, 11458.54165833374, 11479.540818367339, 11535.942320288344, 11571.305721656727, 11174.10607151426, 11366.772664546663, 11383.658490245305, 11771.529138834521, 11801.88198118025, 8720.127987201357, 8835.911640883509, 8852.911470885208, 8860.601272942733, 8914.0, 6096.664683442454, 6299.464545513682, 6314.210723659543, 6332.556721029484, 6333.524985626004, 10807.72980675458, 11091.778164436504, 11341.886581134084, 11345.602903898323, 11389.829152562552, 11274.830877536679, 11370.715732106433, 11410.315381077162, 11416.143789215675, 11439.313641181556, 7622.885656715264, 8159.428839981144, 8320.791980200544, 8359.832803344021, 8459.450135741157, 9251.444913304955, 9273.0, 9368.906310936803, 9370.578323975302, 9375.06249375057, 8156.755297341089, 8327.0, 8386.329093672613, 8432.36757243197, 8516.105808890225, 8106.0, 8217.30152937007, 8260.710875119343, 8277.130901255281, 8358.03882553514, 7213.6753846076, 7432.739854105081, 7436.367908727602, 7451.81370465721, 7454.4781865268915, 5146.6911985281, 5189.896202075862, 5191.584673226208, 5226.137687281708, 5270.7101109439345, 4874.975625121851, 4928.630352723489, 5004.274380214922, 5010.69935803853, 5028.698278103326], "value": 4683.63645368965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.22493365524684, 335.67271909887853, 344.29419689636256, 345.0665948609012, 346.2382757932549, 5279.868003299915, 5363.544098751606, 5387.622866399359, 5391.56867450604, 5408.891177308652, 5266.289050978116, 5267.631265811395, 5315.840524784263, 5326.334208223972, 5327.0, 4085.55058943516, 4211.894702632428, 4216.494020717518, 4231.428757117796, 4249.575042495757, 4135.415365396627, 4161.625453709165, 4225.894352641193, 4233.322668373061, 4246.3205887058075, 4143.233501802161, 4153.169366126786, 4154.896127596804, 4163.0, 4174.895627609304, 3983.521977362706, 3995.700322475797, 4076.9196163016823, 4112.300908845504, 4125.339156244236, 3653.598104208535, 3657.6708096271195, 3667.9266414671556, 3669.724770642186, 3679.3009328227818, 4897.975510122426, 5043.193089105725, 5078.0, 5165.199394093921, 5172.508611681884, 4566.817327306937, 4659.88350291242, 4688.2733176357715, 4710.7859306126065, 4713.363695901044, 5375.543078838304, 5429.755660995264, 5430.91853622196, 5448.8365349039195, 5476.67139971603, 3917.5690674025836, 4004.279229738646, 4005.0, 4007.31875581149, 4013.0, 5665.320161580596, 5680.403557626431, 5681.715914204273, 5698.629589076738, 5709.942900570983, 4114.835406583763, 4117.1678603021965, 4123.319652257344, 4125.525564560054, 4129.628333450004, 4052.777097259679, 4212.978935105366, 4220.388043733703, 4221.767802770877, 4222.1766755483, 4662.673612847136, 4669.4630117536235, 4675.438947326343, 4685.344051832746, 4694.530546945346, 5464.316960379952, 5465.234867118606, 5474.206240095164, 5476.7535460904355, 5481.780728770885, 1417.8653027962328, 1424.9715005699827, 1444.9885080443732, 1454.5163932788496, 1469.0891647178817, 926.7822061815468, 943.4905151218403, 945.1635302756972, 952.6522819170953, 961.9803008810543, 4566.88582785433, 4694.924824240128, 4705.247160454311, 4723.4331880174595, 4755.714657120584, 4943.456219815817, 4986.177280748709, 4989.850304490871, 4997.650164488451, 5009.423916249605, 4692.249240121564, 5087.728067983005, 5122.513361230677, 5261.736913154327, 5304.761285742151, 2987.5070613348553, 3012.5330573761103, 3014.427258820817, 3019.2150040989063, 3023.229076585457, 5607.467290607386, 5808.361080281165, 5827.0, 5862.632338907541, 5865.472107510344, 5737.512311453574, 5740.311162660508, 5764.827055188351, 5796.42035796417, 5803.390643982406, 5718.228039214736, 5795.334755521677, 5886.793962211302, 5898.8230353089475, 5903.203067585907, 5414.269073675004, 5523.226748255167, 5639.702868340222, 5693.914591281051, 5735.369109398046, 5583.162525621189, 5585.916211256753, 5589.7143656958315, 5600.747966341443, 5622.662640241599, 5618.0, 5756.827295181151, 5841.18223448709, 5844.574501581966, 5856.677882716492, 4074.2258970795456, 4101.118259574206, 4117.75293482392, 4127.33962565988, 4127.545969943364, 5769.740361683818, 5775.556110972257, 5796.130580412803, 5799.898019376305, 5806.635440671524, 5411.918821217606, 5532.2254884315425, 5635.605507614428, 5643.3792282848035, 5659.292588426447, 5559.16612508127, 5565.526930210977, 5572.832815015556, 5592.356878958972, 5649.548036157015, 5491.807786727445, 5492.258545096361, 5497.285352904097, 5511.448855114457, 5549.639273447213, 5291.788328466972, 5628.127640215773, 5632.239647647679, 5652.265205523256, 5685.60200785941, 5436.3476382834315, 5456.863578410572, 5530.170474428707, 5549.195366671891, 5563.7496312666835], "value": 4209.514578567619, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.7382353088186, 759.244551671087, 808.0908977400425, 819.3731795176687, 839.907610162882, 14955.925220373896, 15885.602859928478, 15900.92049539752, 15959.521214363573, 16036.118013509253, 16102.59743506413, 16120.193990300497, 16124.274407651654, 16159.596010099753, 16168.27242774075, 681.635325101071, 799.4883274704194, 815.898012748408, 818.2022528035178, 823.8681810910256, 829.2578142562403, 834.6869923778584, 835.2607941971369, 835.5905606252923, 836.0636087581917, 875.689130358723, 881.1188811188811, 881.6561540999015, 881.7971866470716, 881.8765372847806, 713.0836874616119, 836.8200836820113, 865.4504389712515, 865.8960924689046, 874.1389731114847, 631.8862604731147, 632.2728861808911, 632.3929028132998, 632.4181752787447, 632.5034847644577, 13074.215547067206, 13111.737765244641, 13143.27711975841, 13150.73698526024, 13158.07893447459, 12818.423170957329, 13091.345432728325, 13139.737205255842, 13312.667183320398, 13502.257375844423, 11612.129090318176, 12013.0, 12050.638480845588, 12163.209391389531, 12245.816312755316, 3829.48301979232, 3843.250566139604, 3914.628110329514, 3921.705872059607, 3966.841326346943, 59304.70347648277, 59633.0, 59724.70137649327, 60176.0, 60393.0, 11141.108711303077, 11250.268732532359, 11260.605878794202, 11410.771784564262, 11415.60045398407, 19948.50128746793, 19957.0, 20044.69932951007, 20046.09792559338, 20060.097295621734, 8238.340932725368, 8292.04641466227, 8322.29260512851, 8427.456109063469, 8457.746267611981, 4953.7523123844385, 4954.677945933503, 5018.422881368616, 5070.923936140961, 5084.898302034013, 3443.741719371057, 3558.252766918976, 3561.893143205708, 3588.964110358915, 3591.4792355108884, 621.3786213786213, 623.7816764132517, 626.7931582577829, 639.133973465947, 645.4320198225531, 616.950643948483, 715.9463040272001, 722.887952367384, 740.8740514112613, 748.4798065344642, 9798.31411801167, 9838.704838854846, 9904.108630223314, 9921.156701680293, 9954.0, 690.2441826200303, 774.3727580659571, 774.6862520679022, 786.6892577431883, 792.7304716396337, 4728.550787675165, 4742.07529531738, 4800.0, 4852.587530059914, 4921.089598424303, 64121.35878641154, 64414.355856441776, 65380.0, 65456.67271663611, 68832.6558367205, 58673.70663146742, 59143.40856591465, 59893.70053149793, 60009.39990600126, 61249.0, 52929.47070529246, 52972.0, 54261.84512471388, 54565.72717136389, 54908.450915491136, 9009.684661037094, 9127.22418594427, 9169.954150229207, 9237.445753254826, 9361.204297634777, 36675.816620917794, 36966.26067478689, 37006.25987480181, 37070.8146459266, 37271.6272837279, 45673.55922559812, 49250.75374623104, 49654.0, 50289.0, 50290.994180115464, 11818.763624727286, 11937.582184623496, 12006.939965300118, 12127.514899404277, 12361.876381236072, 12854.0, 13068.607941761762, 13370.665733356744, 13399.33003349809, 13513.729725405634, 9919.603215871284, 12968.610941671388, 13285.257527615351, 13381.330933453093, 13523.120997135155, 13219.40512676951, 13447.327633618084, 13584.592462226148, 13845.376958037114, 13892.652683682989, 13401.463941442233, 13509.59471215865, 13518.324083795575, 14280.143191408548, 14280.357383917957, 10852.240343175901, 11025.117990560575, 11069.114470842153, 11069.417449047429, 11388.316700997966, 11435.256708313933, 11526.538938442369, 11818.0, 11836.171467997157, 11945.283283003047], "value": 7013.311051152066, "unit": "score"}], "name": "dromaeo_css", "value": 7207.06322637283, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 126.0, 126.0, 185.0, 127.0, 126.0, 147.0, 124.0, 158.0, 124.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 115.0, 75.0, 75.0, 74.0, 74.0, 96.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 111.0, 82.0, 83.0, 82.0, 122.0, 82.0, 84.0, 133.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 62.0, 66.0, 63.0, 61.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 78.0, 80.0, 80.0, 86.0, 84.0, 80.0, 126.0, 79.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 120.0, 119.0, 131.0, 118.0, 118.0, 127.0, 121.0, 138.0, 122.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 163.0, 159.0, 168.0, 162.0, 157.0, 159.0, 163.0, 158.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 63.0, 63.0, 63.0, 65.0, 62.0, 62.0, 60.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1494.0, "alertThreshold": 2.0}]} 15:19:01 INFO - Return code: 0 15:19:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:19:01 ERROR - # TBPL SUCCESS # 15:19:01 INFO - Running post-action listener: _resource_record_post_action 15:19:01 INFO - ##### 15:19:01 INFO - ##### Finished run-tests step (success) 15:19:01 INFO - ##### 15:19:01 INFO - Running post-run listener: _resource_record_post_run 15:19:01 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548298752; Read time: 16; Write time: 639200 15:19:01 INFO - TinderboxPrint: CPU usage
13.2% 15:19:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:19:01 INFO - TinderboxPrint: I/O write bytes / time
548,298,752 / 639,200 15:19:01 INFO - TinderboxPrint: CPU idle
8,258.4 (86.8%) 15:19:01 INFO - TinderboxPrint: CPU user
1,208.3 (12.7%) 15:19:01 INFO - TinderboxPrint: Swap in / out
0 / 0 15:19:01 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 257228800; Read time: 0; Write time: 415784 15:19:01 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288735232; Read time: 16; Write time: 223312 15:19:01 INFO - Running post-run listener: _upload_blobber_files 15:19:01 INFO - Blob upload gear active. 15:19:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:19:01 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:19:01 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:19:01 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:19:02 INFO - (blobuploader) - INFO - Open directory for files ... 15:19:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:19:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:19:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:06 INFO - (blobuploader) - INFO - Done attempting. 15:19:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:19:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:19:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:07 INFO - (blobuploader) - INFO - Done attempting. 15:19:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:19:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:19:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:19:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:19:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:19:08 INFO - (blobuploader) - INFO - Done attempting. 15:19:08 INFO - (blobuploader) - INFO - Iteration through files over. 15:19:08 INFO - Return code: 0 15:19:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:19:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:19:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/79f69d03037691d6657c0e7798a1cc23a2195ccca52638ab664c7f14902c8243efd82aa520d208b84ac21df31ce146a12302f16ac4e833851642a23f2543f56b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7e2795572501ff9b79daca9a7a67c73abb9cfe524738c62fb48d2c6b3e9414e768c97d02e0b0b478bae382fc965ef77596d0a36ab204443f6d34c4392aca89b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c4341a6fb2da99f3dc5f3b943abcbbb5fadd69198b1c22dde5f0377bf98d51327a666626985460d39320a0460f1b10f7867ab59f702e7b8ec0776b42e06302c"} 15:19:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:19:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:19:08 INFO - Contents: 15:19:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/79f69d03037691d6657c0e7798a1cc23a2195ccca52638ab664c7f14902c8243efd82aa520d208b84ac21df31ce146a12302f16ac4e833851642a23f2543f56b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7e2795572501ff9b79daca9a7a67c73abb9cfe524738c62fb48d2c6b3e9414e768c97d02e0b0b478bae382fc965ef77596d0a36ab204443f6d34c4392aca89b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c4341a6fb2da99f3dc5f3b943abcbbb5fadd69198b1c22dde5f0377bf98d51327a666626985460d39320a0460f1b10f7867ab59f702e7b8ec0776b42e06302c"} 15:19:08 INFO - Running post-run listener: copy_logs_to_upload_dir 15:19:08 INFO - Copying logs to upload dir... 15:19:08 INFO - mkdir: /builds/slave/test/build/upload/logs 15:19:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.691435 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-31 15:19:08.480026) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:08.483866) ========= 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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/79f69d03037691d6657c0e7798a1cc23a2195ccca52638ab664c7f14902c8243efd82aa520d208b84ac21df31ce146a12302f16ac4e833851642a23f2543f56b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7e2795572501ff9b79daca9a7a67c73abb9cfe524738c62fb48d2c6b3e9414e768c97d02e0b0b478bae382fc965ef77596d0a36ab204443f6d34c4392aca89b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c4341a6fb2da99f3dc5f3b943abcbbb5fadd69198b1c22dde5f0377bf98d51327a666626985460d39320a0460f1b10f7867ab59f702e7b8ec0776b42e06302c"} build_url:https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/PRWoPeYoSeufXo3KBOplPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/79f69d03037691d6657c0e7798a1cc23a2195ccca52638ab664c7f14902c8243efd82aa520d208b84ac21df31ce146a12302f16ac4e833851642a23f2543f56b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7e2795572501ff9b79daca9a7a67c73abb9cfe524738c62fb48d2c6b3e9414e768c97d02e0b0b478bae382fc965ef77596d0a36ab204443f6d34c4392aca89b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6c4341a6fb2da99f3dc5f3b943abcbbb5fadd69198b1c22dde5f0377bf98d51327a666626985460d39320a0460f1b10f7867ab59f702e7b8ec0776b42e06302c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:08.509683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:08.510004) ========= 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=db0483c52d987ecb441a263b000001ad-1464731699.39678-1115964110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004696 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:19:08.595280) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 15:19:08.596153) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 15:19:08.596832) ========= ========= Total master_lag: 0.20 =========