builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-030 starttime: 1464757532.82 results: success (0) buildid: 20160531193138 builduid: 000e5e3d3cec46ea954987222d06be41 revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.823393) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.824015) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.824317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.840129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.840736) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-31 22:05:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.6M=0.001s 2016-05-31 22:05:32 (20.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.969123) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:32.969788) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020720 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.023008) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.023656) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 25321494921c824703a605127fb1f99b1faf5910 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 25321494921c824703a605127fb1f99b1faf5910 --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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-31 22:05:33,045 truncating revision to first 12 chars 2016-05-31 22:05:33,045 Setting DEBUG logging. 2016-05-31 22:05:33,045 attempt 1/10 2016-05-31 22:05:33,046 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/25321494921c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 22:05:33,412 unpacking tar archive at: mozilla-central-25321494921c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.697055 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.735914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.736232) ========= script_repo_revision: 25321494921c824703a605127fb1f99b1faf5910 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.736654) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.736926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 22:05:33.750124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-31 22:05:33.750532) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:05:33 INFO - MultiFileLogger online at 20160531 22:05:33 in /builds/slave/test 22:05:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:05:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:05:33 INFO - {'append_to_log': False, 22:05:33 INFO - 'base_work_dir': '/builds/slave/test', 22:05:33 INFO - 'blob_upload_branch': 'Firefox', 22:05:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:05:33 INFO - 'branch': 'Firefox', 22:05:33 INFO - 'buildbot_json_path': 'buildprops.json', 22:05:33 INFO - 'config_files': ('talos/linux_config.py',), 22:05:33 INFO - 'default_actions': ('clobber', 22:05:33 INFO - 'read-buildbot-config', 22:05:33 INFO - 'download-and-extract', 22:05:33 INFO - 'populate-webroot', 22:05:33 INFO - 'create-virtualenv', 22:05:33 INFO - 'install', 22:05:33 INFO - 'run-tests'), 22:05:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:05:33 INFO - 'download_minidump_stackwalk': True, 22:05:33 INFO - 'download_symbols': 'ondemand', 22:05:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:05:33 INFO - 'tooltool.py': '/tools/tooltool.py', 22:05:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:05:33 INFO - '/tools/misc-python/virtualenv.py')}, 22:05:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:05:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:05:33 INFO - 'installer_path': 'installer.exe', 22:05:33 INFO - 'log_level': 'info', 22:05:33 INFO - 'log_name': 'talos', 22:05:33 INFO - 'log_to_console': True, 22:05:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:05:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:05:33 INFO - 'opt_config_files': (), 22:05:33 INFO - 'pip_index': False, 22:05:33 INFO - 'sps_profile': False, 22:05:33 INFO - 'sps_profile_interval': 0, 22:05:33 INFO - 'suite': 'dromaeojs-e10s', 22:05:33 INFO - 'system_bits': '32', 22:05:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:05:33 INFO - 'title': 'talos-linux64-ix-030', 22:05:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:05:33 INFO - 'use_talos_json': True, 22:05:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:05:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:05:33 INFO - 'work_dir': 'build'} 22:05:33 INFO - ##### 22:05:33 INFO - ##### Running clobber step. 22:05:33 INFO - ##### 22:05:33 INFO - Running pre-action listener: _resource_record_pre_action 22:05:33 INFO - Running main action method: clobber 22:05:33 INFO - rmtree: /builds/slave/test/build 22:05:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - ##### 22:05:34 INFO - ##### Finished clobber step (success) 22:05:34 INFO - ##### 22:05:34 INFO - ##### 22:05:34 INFO - ##### Running read-buildbot-config step. 22:05:34 INFO - ##### 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: read_buildbot_config 22:05:34 INFO - Using buildbot properties: 22:05:34 INFO - { 22:05:34 INFO - "project": "", 22:05:34 INFO - "product": "firefox", 22:05:34 INFO - "script_repo_revision": "production", 22:05:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:05:34 INFO - "repository": "", 22:05:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:05:34 INFO - "buildid": "20160531193138", 22:05:34 INFO - "pgo_build": "True", 22:05:34 INFO - "basedir": "/builds/slave/test", 22:05:34 INFO - "buildnumber": 50, 22:05:34 INFO - "slavename": "talos-linux64-ix-030", 22:05:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:05:34 INFO - "platform": "ubuntu64_hw", 22:05:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 22:05:34 INFO - "branch": "mozilla-central", 22:05:34 INFO - "repo_path": "mozilla-central", 22:05:34 INFO - "revision": "25321494921c824703a605127fb1f99b1faf5910", 22:05:34 INFO - "stage_platform": "linux64-pgo", 22:05:34 INFO - "builduid": "000e5e3d3cec46ea954987222d06be41", 22:05:34 INFO - "slavebuilddir": "test" 22:05:34 INFO - } 22:05:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:05:34 INFO - Running post-action listener: _resource_record_post_action 22:05:34 INFO - ##### 22:05:34 INFO - ##### Finished read-buildbot-config step (success) 22:05:34 INFO - ##### 22:05:34 INFO - ##### 22:05:34 INFO - ##### Running download-and-extract step. 22:05:34 INFO - ##### 22:05:34 INFO - Running pre-action listener: _resource_record_pre_action 22:05:34 INFO - Running main action method: download_and_extract 22:05:34 INFO - mkdir: /builds/slave/test/build/tests 22:05: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')]} 22:05:34 INFO - https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:05:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:38 INFO - Downloaded 1361 bytes. 22:05:38 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:05:38 INFO - Using the following test package requirements: 22:05:38 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:38 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:05:38 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:05:38 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'jsshell-linux-x86_64.zip'], 22:05:38 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:05:38 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:05:38 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:05:38 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:05:38 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:05:38 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:05:38 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:05:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:05:38 INFO - https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:39 INFO - Downloaded 24413121 bytes. 22:05: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'] 22:05: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 22:05:40 INFO - Return code: 0 22:05:40 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 22:05: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')]} 22:05:40 INFO - https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:05:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:05:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:44 INFO - Downloaded 24413121 bytes. 22:05:44 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'] 22:05:44 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 22:05:45 INFO - Return code: 0 22:05:45 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')]} 22:05:45 INFO - https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:05:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:05:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:46 INFO - Downloaded 10834055 bytes. 22:05:46 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'] 22:05:46 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 22:05:46 INFO - Return code: 0 22:05: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')]} 22:05:46 INFO - https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:05:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IcUiywEKTSeGJVnLLB9AYw/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 22:05:48 INFO - Downloaded 58989741 bytes. 22:05:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:48 INFO - mkdir: /builds/slave/test/properties 22:05:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:05:48 INFO - Writing to file /builds/slave/test/properties/build_url 22:05:48 INFO - Contents: 22:05:48 INFO - build_url:https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:05:48 INFO - Running post-action listener: _resource_record_post_action 22:05:48 INFO - Running post-action listener: set_extra_try_arguments 22:05:48 INFO - ##### 22:05:48 INFO - ##### Finished download-and-extract step (success) 22:05:48 INFO - ##### 22:05:48 INFO - ##### 22:05:48 INFO - ##### Running populate-webroot step. 22:05:48 INFO - ##### 22:05:48 INFO - Running pre-action listener: _resource_record_pre_action 22:05:48 INFO - Running main action method: populate_webroot 22:05:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:05:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:05:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:05:48 INFO - '--tppagecycles', 22:05:48 INFO - '7'], 22:05:48 INFO - 'tests': ['tsvgm']}}, 22:05:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:05:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:05:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:05:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:05:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:05:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['damp', 'tps']}, 22:05:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'tests': ['damp', 'tps']}, 22:05:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['dromaeo_dom']}, 22:05:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:05:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'other-e10s': {'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['a11yr', 22:05:48 INFO - 'ts_paint', 22:05:48 INFO - 'tpaint', 22:05:48 INFO - 'sessionrestore', 22:05:48 INFO - 'sessionrestore_no_auto_restore', 22:05:48 INFO - 'tabpaint']}, 22:05:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['tsvgx', 22:05:48 INFO - 'tsvgr_opacity', 22:05:48 INFO - 'tart', 22:05:48 INFO - 'tscrollx', 22:05:48 INFO - 'cart']}, 22:05:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:05:48 INFO - 'tsvgr_opacity', 22:05:48 INFO - 'tart', 22:05:48 INFO - 'tscrollx', 22:05:48 INFO - 'cart']}, 22:05:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'talos_options': ['--disable-e10s'], 22:05:48 INFO - 'tests': ['tp5o']}, 22:05:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'tests': ['tp5o']}, 22:05:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'talos_options': ['--disable-e10s', 22:05:48 INFO - '--xperf_path', 22:05:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:48 INFO - 'tests': ['tp5n']}, 22:05:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:05:48 INFO - 'talos_options': ['--xperf_path', 22:05:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:05:48 INFO - 'tests': ['tp5n']}}, 22:05:48 INFO - 'talos.zip': {'path': '', 22:05:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:05:48 INFO - Running post-action listener: _resource_record_post_action 22:05:48 INFO - ##### 22:05:48 INFO - ##### Finished populate-webroot step (success) 22:05:48 INFO - ##### 22:05:48 INFO - ##### 22:05:48 INFO - ##### Running create-virtualenv step. 22:05:48 INFO - ##### 22:05:48 INFO - Running pre-action listener: _resource_record_pre_action 22:05:48 INFO - Running main action method: create_virtualenv 22:05:48 INFO - Creating virtualenv /builds/slave/test/build/venv 22:05:48 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 22:05:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:05:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:05:49 INFO - Using real prefix '/usr' 22:05:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:05:49 INFO - Installing distribute.............................................................................................................................................................................................done. 22:05:51 INFO - Installing pip.................done. 22:05:51 INFO - Return code: 0 22:05:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:05: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', 'pip>=1.5'] in /builds/slave/test/build 22:05: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 pip>=1.5 22:05:51 INFO - Using env: {'DISPLAY': ':0', 22:05:51 INFO - 'HOME': '/home/cltbld', 22:05:51 INFO - 'LANG': 'en_US.UTF-8', 22:05:51 INFO - 'LANGUAGE': 'en_US:en', 22:05:51 INFO - 'LOGNAME': 'cltbld', 22:05:51 INFO - 'MAIL': '/var/mail/cltbld', 22:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:51 INFO - 'MOZ_NO_REMOTE': '1', 22:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:51 INFO - 'NO_EM_RESTART': '1', 22:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:51 INFO - 'PWD': '/builds/slave/test', 22:05:51 INFO - 'SHELL': '/bin/bash', 22:05:51 INFO - 'SHLVL': '1', 22:05:51 INFO - 'TERM': 'linux', 22:05:51 INFO - 'TMOUT': '86400', 22:05:51 INFO - 'USER': 'cltbld', 22:05:51 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:51 INFO - '_': '/tools/buildbot/bin/python'} 22:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:51 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 22:05:51 INFO - Cleaning up... 22:05:51 INFO - Return code: 0 22:05:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:05: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')]} 22:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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')]} 22:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05: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', '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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:05: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 psutil>=3.1.1 22:05:51 INFO - Using env: {'DISPLAY': ':0', 22:05:51 INFO - 'HOME': '/home/cltbld', 22:05:51 INFO - 'LANG': 'en_US.UTF-8', 22:05:51 INFO - 'LANGUAGE': 'en_US:en', 22:05:51 INFO - 'LOGNAME': 'cltbld', 22:05:51 INFO - 'MAIL': '/var/mail/cltbld', 22:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:51 INFO - 'MOZ_NO_REMOTE': '1', 22:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:51 INFO - 'NO_EM_RESTART': '1', 22:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:51 INFO - 'PWD': '/builds/slave/test', 22:05:51 INFO - 'SHELL': '/bin/bash', 22:05:51 INFO - 'SHLVL': '1', 22:05:51 INFO - 'TERM': 'linux', 22:05:51 INFO - 'TMOUT': '86400', 22:05:51 INFO - 'USER': 'cltbld', 22:05:51 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:51 INFO - '_': '/tools/buildbot/bin/python'} 22:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:51 INFO - Downloading/unpacking psutil>=3.1.1 22:05:51 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 22:05:51 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 22:05:51 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 22:05:51 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 22:05:51 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 22:05:51 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 22:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:05:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:53 INFO - Installing collected packages: psutil 22:05:53 INFO - Running setup.py install for psutil 22:05:54 INFO - building 'psutil._psutil_linux' extension 22:05:54 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 22:05:54 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 22:05:54 INFO - building 'psutil._psutil_posix' extension 22:05:54 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 22:05:54 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 22:05:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:05:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:05:54 INFO - Successfully installed psutil 22:05:54 INFO - Cleaning up... 22:05:54 INFO - Return code: 0 22:05:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:05:54 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')]} 22:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:54 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')]} 22:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:54 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:05:54 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 22:05:54 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 22:05:54 INFO - Using env: {'DISPLAY': ':0', 22:05:54 INFO - 'HOME': '/home/cltbld', 22:05:54 INFO - 'LANG': 'en_US.UTF-8', 22:05:54 INFO - 'LANGUAGE': 'en_US:en', 22:05:54 INFO - 'LOGNAME': 'cltbld', 22:05:54 INFO - 'MAIL': '/var/mail/cltbld', 22:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:54 INFO - 'MOZ_NO_REMOTE': '1', 22:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:54 INFO - 'NO_EM_RESTART': '1', 22:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:54 INFO - 'PWD': '/builds/slave/test', 22:05:54 INFO - 'SHELL': '/bin/bash', 22:05:54 INFO - 'SHLVL': '1', 22:05:54 INFO - 'TERM': 'linux', 22:05:54 INFO - 'TMOUT': '86400', 22:05:54 INFO - 'USER': 'cltbld', 22:05:54 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:54 INFO - '_': '/tools/buildbot/bin/python'} 22:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:05: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 22:05: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 22:05: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 22:05: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 22:05: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 22:05: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 22:05:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:05:56 INFO - Installing collected packages: mozsystemmonitor 22:05:56 INFO - Running setup.py install for mozsystemmonitor 22:05:56 INFO - Successfully installed mozsystemmonitor 22:05:56 INFO - Cleaning up... 22:05:56 INFO - Return code: 0 22:05:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:05:56 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')]} 22:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:56 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')]} 22:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:05:56 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:05:56 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 22:05:56 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 22:05:56 INFO - Using env: {'DISPLAY': ':0', 22:05:56 INFO - 'HOME': '/home/cltbld', 22:05:56 INFO - 'LANG': 'en_US.UTF-8', 22:05:56 INFO - 'LANGUAGE': 'en_US:en', 22:05:56 INFO - 'LOGNAME': 'cltbld', 22:05:56 INFO - 'MAIL': '/var/mail/cltbld', 22:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:05:56 INFO - 'MOZ_NO_REMOTE': '1', 22:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:05:56 INFO - 'NO_EM_RESTART': '1', 22:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:05:56 INFO - 'PWD': '/builds/slave/test', 22:05:56 INFO - 'SHELL': '/bin/bash', 22:05:56 INFO - 'SHLVL': '1', 22:05:56 INFO - 'TERM': 'linux', 22:05:56 INFO - 'TMOUT': '86400', 22:05:56 INFO - 'USER': 'cltbld', 22:05:56 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:05:56 INFO - '_': '/tools/buildbot/bin/python'} 22:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:05:57 INFO - Downloading/unpacking blobuploader==1.2.4 22:05:57 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 22:05:57 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 22:05:57 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 22:05:57 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 22:05:57 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 22:05:57 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 22:05:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:05:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:05:58 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 22:05:58 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 22:05:58 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 22:05:58 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 22:05:58 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 22:05:58 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 22:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:05:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:05:59 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 22:05:59 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 22:05:59 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 22:05:59 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 22:05:59 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 22:05:59 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 22:05:59 INFO - Downloading docopt-0.6.1.tar.gz 22:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:05:59 INFO - Installing collected packages: blobuploader, requests, docopt 22:05:59 INFO - Running setup.py install for blobuploader 22:05:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:05:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:05:59 INFO - Running setup.py install for requests 22:06:00 INFO - Running setup.py install for docopt 22:06:00 INFO - Successfully installed blobuploader requests docopt 22:06:00 INFO - Cleaning up... 22:06:00 INFO - Return code: 0 22:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:00 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:06:00 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 22:06:00 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 22:06:00 INFO - Using env: {'DISPLAY': ':0', 22:06:00 INFO - 'HOME': '/home/cltbld', 22:06:00 INFO - 'LANG': 'en_US.UTF-8', 22:06:00 INFO - 'LANGUAGE': 'en_US:en', 22:06:00 INFO - 'LOGNAME': 'cltbld', 22:06:00 INFO - 'MAIL': '/var/mail/cltbld', 22:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:00 INFO - 'MOZ_NO_REMOTE': '1', 22:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:00 INFO - 'NO_EM_RESTART': '1', 22:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:00 INFO - 'PWD': '/builds/slave/test', 22:06:00 INFO - 'SHELL': '/bin/bash', 22:06:00 INFO - 'SHLVL': '1', 22:06:00 INFO - 'TERM': 'linux', 22:06:00 INFO - 'TMOUT': '86400', 22:06:00 INFO - 'USER': 'cltbld', 22:06:00 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:00 INFO - '_': '/tools/buildbot/bin/python'} 22:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:00 INFO - Running setup.py (path:/tmp/pip-PcrMKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:00 INFO - Running setup.py (path:/tmp/pip-FvS2KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:00 INFO - Running setup.py (path:/tmp/pip-hs9E8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:00 INFO - Running setup.py (path:/tmp/pip-C_zrRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:00 INFO - Running setup.py (path:/tmp/pip-U5olBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:00 INFO - Running setup.py (path:/tmp/pip-SznwgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:00 INFO - Running setup.py (path:/tmp/pip-bx_ODZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:00 INFO - Running setup.py (path:/tmp/pip-sn3j9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:01 INFO - Running setup.py (path:/tmp/pip-IZjgrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:01 INFO - Running setup.py (path:/tmp/pip-mNOjbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:01 INFO - Running setup.py (path:/tmp/pip-K0t1HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:01 INFO - Running setup.py (path:/tmp/pip-9GsYOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:01 INFO - Running setup.py (path:/tmp/pip-diDwbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:01 INFO - Running setup.py (path:/tmp/pip-ZFSrAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:01 INFO - Running setup.py (path:/tmp/pip-YITK1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:01 INFO - Running setup.py (path:/tmp/pip-HZyS72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:01 INFO - Running setup.py (path:/tmp/pip-0oR2ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:01 INFO - Running setup.py install for manifestparser 22:06:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:01 INFO - Running setup.py install for mozcrash 22:06:01 INFO - Running setup.py install for mozdebug 22:06:01 INFO - Running setup.py install for mozdevice 22:06:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for mozfile 22:06:02 INFO - Running setup.py install for mozhttpd 22:06:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for mozinfo 22:06:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for mozInstall 22:06:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for mozleak 22:06:02 INFO - Running setup.py install for mozlog 22:06:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for moznetwork 22:06:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:02 INFO - Running setup.py install for mozprocess 22:06:02 INFO - Running setup.py install for mozprofile 22:06:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:03 INFO - Running setup.py install for mozrunner 22:06:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:03 INFO - Running setup.py install for mozscreenshot 22:06:03 INFO - Running setup.py install for moztest 22:06:03 INFO - Running setup.py install for mozversion 22:06:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:03 INFO - Cleaning up... 22:06:03 INFO - Return code: 0 22:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:03 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:06:03 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 22:06:03 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 22:06:03 INFO - Using env: {'DISPLAY': ':0', 22:06:03 INFO - 'HOME': '/home/cltbld', 22:06:03 INFO - 'LANG': 'en_US.UTF-8', 22:06:03 INFO - 'LANGUAGE': 'en_US:en', 22:06:03 INFO - 'LOGNAME': 'cltbld', 22:06:03 INFO - 'MAIL': '/var/mail/cltbld', 22:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:03 INFO - 'MOZ_NO_REMOTE': '1', 22:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:03 INFO - 'NO_EM_RESTART': '1', 22:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:03 INFO - 'PWD': '/builds/slave/test', 22:06:03 INFO - 'SHELL': '/bin/bash', 22:06:03 INFO - 'SHLVL': '1', 22:06:03 INFO - 'TERM': 'linux', 22:06:03 INFO - 'TMOUT': '86400', 22:06:03 INFO - 'USER': 'cltbld', 22:06:03 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:03 INFO - '_': '/tools/buildbot/bin/python'} 22:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:03 INFO - Running setup.py (path:/tmp/pip-L9Akw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:03 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)) 22:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:03 INFO - Running setup.py (path:/tmp/pip-SqkWxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:03 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)) 22:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:03 INFO - Running setup.py (path:/tmp/pip-lh3fYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:03 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)) 22:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:03 INFO - Running setup.py (path:/tmp/pip-rmd1py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:03 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)) 22:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:03 INFO - Running setup.py (path:/tmp/pip-HdNpFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:04 INFO - Running setup.py (path:/tmp/pip-PSJ5Gh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:04 INFO - Running setup.py (path:/tmp/pip-mrQ32N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:04 INFO - Running setup.py (path:/tmp/pip-WpLRx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:04 INFO - Running setup.py (path:/tmp/pip-7dNRA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:04 INFO - Running setup.py (path:/tmp/pip-ivEcpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:04 INFO - Running setup.py (path:/tmp/pip-QH4x9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:04 INFO - Running setup.py (path:/tmp/pip-Z0unYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:04 INFO - Running setup.py (path:/tmp/pip-4mJVuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:04 INFO - Running setup.py (path:/tmp/pip-jTwUX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:04 INFO - Running setup.py (path:/tmp/pip-OQDvQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:04 INFO - Running setup.py (path:/tmp/pip-wCyLQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:04 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)) 22:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:04 INFO - Running setup.py (path:/tmp/pip-Wdvcw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:04 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)) 22:06:04 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)) 22:06:04 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)) 22:06:04 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)) 22:06:04 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)) 22:06:04 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)) 22:06:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06:06 INFO - Downloading blessings-1.6.tar.gz 22:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:06 INFO - Installing collected packages: blessings 22:06:06 INFO - Running setup.py install for blessings 22:06:06 INFO - Successfully installed blessings 22:06:06 INFO - Cleaning up... 22:06:07 INFO - Return code: 0 22:06:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:07 INFO - Reading from file tmpfile_stdout 22:06:07 INFO - Current package versions: 22:06:07 INFO - argparse == 1.2.1 22:06:07 INFO - blessings == 1.6 22:06:07 INFO - blobuploader == 1.2.4 22:06:07 INFO - docopt == 0.6.1 22:06:07 INFO - manifestparser == 1.1 22:06:07 INFO - mozInstall == 1.12 22:06:07 INFO - mozcrash == 0.17 22:06:07 INFO - mozdebug == 0.1 22:06:07 INFO - mozdevice == 0.48 22:06:07 INFO - mozfile == 1.2 22:06:07 INFO - mozhttpd == 0.7 22:06:07 INFO - mozinfo == 0.9 22:06:07 INFO - mozleak == 0.1 22:06:07 INFO - mozlog == 3.2 22:06:07 INFO - moznetwork == 0.27 22:06:07 INFO - mozprocess == 0.22 22:06:07 INFO - mozprofile == 0.28 22:06:07 INFO - mozrunner == 6.11 22:06:07 INFO - mozscreenshot == 0.1 22:06:07 INFO - mozsystemmonitor == 0.3 22:06:07 INFO - moztest == 0.7 22:06:07 INFO - mozversion == 1.4 22:06:07 INFO - psutil == 3.1.1 22:06:07 INFO - requests == 1.2.3 22:06:07 INFO - wsgiref == 0.1.2 22:06:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:07 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:06:07 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 22:06:07 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 22:06:07 INFO - Using env: {'DISPLAY': ':0', 22:06:07 INFO - 'HOME': '/home/cltbld', 22:06:07 INFO - 'LANG': 'en_US.UTF-8', 22:06:07 INFO - 'LANGUAGE': 'en_US:en', 22:06:07 INFO - 'LOGNAME': 'cltbld', 22:06:07 INFO - 'MAIL': '/var/mail/cltbld', 22:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:07 INFO - 'MOZ_NO_REMOTE': '1', 22:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:07 INFO - 'NO_EM_RESTART': '1', 22:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:07 INFO - 'PWD': '/builds/slave/test', 22:06:07 INFO - 'SHELL': '/bin/bash', 22:06:07 INFO - 'SHLVL': '1', 22:06:07 INFO - 'TERM': 'linux', 22:06:07 INFO - 'TMOUT': '86400', 22:06:07 INFO - 'USER': 'cltbld', 22:06:07 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:07 INFO - '_': '/tools/buildbot/bin/python'} 22:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 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)) 22:06:07 INFO - Cleaning up... 22:06:07 INFO - Return code: 0 22:06:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:07 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:06:07 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 22:06:07 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 22:06:07 INFO - Using env: {'DISPLAY': ':0', 22:06:07 INFO - 'HOME': '/home/cltbld', 22:06:07 INFO - 'LANG': 'en_US.UTF-8', 22:06:07 INFO - 'LANGUAGE': 'en_US:en', 22:06:07 INFO - 'LOGNAME': 'cltbld', 22:06:07 INFO - 'MAIL': '/var/mail/cltbld', 22:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:07 INFO - 'MOZ_NO_REMOTE': '1', 22:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:07 INFO - 'NO_EM_RESTART': '1', 22:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:07 INFO - 'PWD': '/builds/slave/test', 22:06:07 INFO - 'SHELL': '/bin/bash', 22:06:07 INFO - 'SHLVL': '1', 22:06:07 INFO - 'TERM': 'linux', 22:06:07 INFO - 'TMOUT': '86400', 22:06:07 INFO - 'USER': 'cltbld', 22:06:07 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:07 INFO - '_': '/tools/buildbot/bin/python'} 22:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:07 INFO - Downloading/unpacking jsonschema 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06: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 22:06:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:09 INFO - Downloading/unpacking functools32 (from jsonschema) 22:06:09 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 22:06:09 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 22:06:09 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 22:06:09 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 22:06:09 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 22:06:09 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 22:06:09 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:09 INFO - warning: no files found matching '*.txt' 22:06:09 INFO - no previously-included directories found matching 'build' 22:06:09 INFO - no previously-included directories found matching 'dist' 22:06:09 INFO - no previously-included directories found matching '.git*' 22:06:09 INFO - Installing collected packages: jsonschema, functools32 22:06:09 INFO - Running setup.py install for functools32 22:06:10 INFO - warning: no files found matching '*.txt' 22:06:10 INFO - no previously-included directories found matching 'build' 22:06:10 INFO - no previously-included directories found matching 'dist' 22:06:10 INFO - no previously-included directories found matching '.git*' 22:06:10 INFO - Successfully installed jsonschema functools32 22:06:10 INFO - Cleaning up... 22:06:10 INFO - Return code: 0 22:06:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:06:10 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')]} 22:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:10 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')]} 22:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:10 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 0x7fcc66f8ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x178f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, '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': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', '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 22:06:10 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 22:06:10 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 22:06:10 INFO - Using env: {'DISPLAY': ':0', 22:06:10 INFO - 'HOME': '/home/cltbld', 22:06:10 INFO - 'LANG': 'en_US.UTF-8', 22:06:10 INFO - 'LANGUAGE': 'en_US:en', 22:06:10 INFO - 'LOGNAME': 'cltbld', 22:06:10 INFO - 'MAIL': '/var/mail/cltbld', 22:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:10 INFO - 'MOZ_NO_REMOTE': '1', 22:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:10 INFO - 'NO_EM_RESTART': '1', 22:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:10 INFO - 'PWD': '/builds/slave/test', 22:06:10 INFO - 'SHELL': '/bin/bash', 22:06:10 INFO - 'SHLVL': '1', 22:06:10 INFO - 'TERM': 'linux', 22:06:10 INFO - 'TMOUT': '86400', 22:06:10 INFO - 'USER': 'cltbld', 22:06:10 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:10 INFO - '_': '/tools/buildbot/bin/python'} 22:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:10 INFO - Downloading/unpacking flake8 22:06:10 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 22:06:10 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 22:06:10 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 22:06:10 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 22:06:10 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 22:06:10 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 22:06:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:06:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:06:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:06:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:12 INFO - no previously-included directories found matching 'docs/_build' 22:06:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:06:13 INFO - Running setup.py install for pep8 22:06:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:06:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:06:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:06:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:06:13 INFO - no previously-included directories found matching 'docs/_build' 22:06:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:06:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:06:13 INFO - Cleaning up... 22:06:13 INFO - Return code: 0 22:06:13 INFO - Running post-action listener: _resource_record_post_action 22:06:13 INFO - Running post-action listener: _start_resource_monitoring 22:06:13 INFO - Starting resource monitoring. 22:06:13 INFO - ##### 22:06:13 INFO - ##### Finished create-virtualenv step (success) 22:06:13 INFO - ##### 22:06:13 INFO - ##### 22:06:13 INFO - ##### Running install step. 22:06:13 INFO - ##### 22:06:13 INFO - Running pre-action listener: _resource_record_pre_action 22:06:13 INFO - Running main action method: install 22:06:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:13 INFO - Reading from file tmpfile_stdout 22:06:13 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:13 INFO - Reading from file tmpfile_stdout 22:06:13 INFO - Output received: 22:06:13 INFO - Usage: mozinstall [options] installer 22:06:13 INFO - Options: 22:06:13 INFO - -h, --help show this help message and exit 22:06:13 INFO - -d DEST, --destination=DEST 22:06:13 INFO - Directory to install application into. [default: 22:06:13 INFO - "/builds/slave/test"] 22:06:13 INFO - --app=APP Application being installed. [default: firefox] 22:06:13 INFO - mkdir: /builds/slave/test/build/application 22:06:13 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'] 22:06:13 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 22:06:30 INFO - Reading from file tmpfile_stdout 22:06:30 INFO - Output received: 22:06:30 INFO - /builds/slave/test/build/application/firefox/firefox 22:06:30 INFO - Running post-action listener: _resource_record_post_action 22:06:30 INFO - ##### 22:06:30 INFO - ##### Finished install step (success) 22:06:30 INFO - ##### 22:06:30 INFO - ##### 22:06:30 INFO - ##### Running run-tests step. 22:06:30 INFO - ##### 22:06:30 INFO - Running pre-action listener: _resource_record_pre_action 22:06:30 INFO - Running main action method: run_tests 22:06:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:06:30 INFO - Python 2.7.3 22:06:30 INFO - Return code: 0 22:06:30 INFO - grabbing minidump binary from tooltool 22:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:30 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 0x18e99c0>, '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 0x18ea8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ead50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:06:30 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 22:06:30 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 22:06:30 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 22:06:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:06:30 INFO - Return code: 0 22:06:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:06:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:06:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:06:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:06:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:06:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:06:31 INFO - Return code: 0 22:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:06:31 INFO - Using env: {'DISPLAY': ':0', 22:06:31 INFO - 'HOME': '/home/cltbld', 22:06:31 INFO - 'LANG': 'en_US.UTF-8', 22:06:31 INFO - 'LANGUAGE': 'en_US:en', 22:06:31 INFO - 'LOGNAME': 'cltbld', 22:06:31 INFO - 'MAIL': '/var/mail/cltbld', 22:06:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:06:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:31 INFO - 'MOZ_NO_REMOTE': '1', 22:06:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:31 INFO - 'NO_EM_RESTART': '1', 22:06:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:31 INFO - 'PWD': '/builds/slave/test', 22:06:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:06:31 INFO - 'SHELL': '/bin/bash', 22:06:31 INFO - 'SHLVL': '1', 22:06:31 INFO - 'TERM': 'linux', 22:06:31 INFO - 'TMOUT': '86400', 22:06:31 INFO - 'USER': 'cltbld', 22:06:31 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328', 22:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:31 INFO - '_': '/tools/buildbot/bin/python'} 22:06:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:06:31 INFO - mozversion application_buildid: 20160531193138 22:06:31 INFO - mozversion application_changeset: 25321494921c824703a605127fb1f99b1faf5910 22:06:31 INFO - mozversion application_display_name: Nightly 22:06:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:06:31 INFO - mozversion application_name: Firefox 22:06:31 INFO - mozversion application_remotingname: firefox 22:06:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:06:31 INFO - mozversion application_vendor: Mozilla 22:06:31 INFO - mozversion application_version: 49.0a1 22:06:31 INFO - mozversion platform_buildid: 20160531193138 22:06:31 INFO - mozversion platform_changeset: 25321494921c824703a605127fb1f99b1faf5910 22:06:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:06:31 INFO - mozversion platform_version: 49.0a1 22:06:31 INFO - using testdate: 1464757591 22:06:31 INFO - actual date: 1464757591 22:06:31 INFO - starting webserver on 'localhost:45846' 22:06:31 INFO - SUITE-START | Running 2 tests 22:06:31 INFO - TEST-START | dromaeo_css 22:06:31 INFO - operating with platform_type : linux_ 22:06:31 INFO - Initialising browser for dromaeo_css test... 22:06:31 INFO - TEST-INFO | started process 23477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRItwME/profile http://localhost:45846/getInfo.html) 22:06:36 INFO - PROCESS | 23477 | __metrics Screen width/height:1600/1200 22:06:36 INFO - PROCESS | 23477 | colorDepth:24 22:06:36 INFO - PROCESS | 23477 | Browser inner width/height: 1024/697 22:06:36 INFO - PROCESS | 23477 | __metrics 22:06:37 ERROR - PROCESS | 23477 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:06:37 INFO - TEST-INFO | 23477: exit 0 22:06:37 INFO - Browser initialized. 22:06:37 INFO - Running cycle 1/1 for dromaeo_css test... 22:06:37 INFO - TEST-INFO | started process 23598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRItwME/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:06:49 INFO - PROCESS | 23598 | RSS: Main: 166793216 22:06:49 INFO - PROCESS | 23598 | 22:07:39 INFO - PROCESS | 23598 | 1464757659358 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] 22:09:47 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45846/tests/dromaeo/cssquery-ext.html) 22:09:48 INFO - PROCESS | 23598 | RSS: Main: 171073536 22:09:48 INFO - PROCESS | 23598 | 22:12:50 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-ext.html (next: http://localhost:45846/tests/dromaeo/cssquery-jquery.html) 22:12:51 INFO - PROCESS | 23598 | RSS: Main: 170139648 22:12:51 INFO - PROCESS | 23598 | 22:15:52 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45846/tests/dromaeo/cssquery-mootools.html) 22:15:53 INFO - PROCESS | 23598 | RSS: Main: 171110400 22:15:53 INFO - PROCESS | 23598 | 22:18:55 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45846/tests/dromaeo/cssquery-prototype.html) 22:18:55 INFO - PROCESS | 23598 | RSS: Main: 168656896 22:18:55 INFO - PROCESS | 23598 | 22:21:58 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45846/tests/dromaeo/cssquery-yui.html) 22:21:58 INFO - PROCESS | 23598 | RSS: Main: 170504192 22:21:58 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | Cycle 1(1): loaded http://localhost:45846/tests/dromaeo/cssquery-yui.html (next: http://localhost:45846/tests/dromaeo/cssquery-dojo.html) 22:25:01 INFO - PROCESS | 23598 | RSS: Main: 171892736 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | __start_tp_report 22:25:01 INFO - PROCESS | 23598 | _x_x_mozilla_page_load 22:25:01 INFO - PROCESS | 23598 | _x_x_mozilla_page_load_details 22:25:01 INFO - PROCESS | 23598 | |i|pagename|runs| 22:25:01 INFO - PROCESS | 23598 | |0;dojo.html;3149.11824689087;3155.6371017333013;3156.5580818685376;3184.633767116782;3202.791818531796;5376.166694162406;5404.648697834638;5445.128779395298;5483.890322193553;5534.501894829465;5280.392754833186;5373.167159090338;5427.050266203419;5483.533899618537;5503.147012213103;4848.272759086139;4872.4640289568115;4968.900621987558;5023.522765337287;5065.468125846788;4881.926771098437;5088.211327244283;5095.770690318926;5106.387233531981;5121.12940800063;4827.927581086286;4873.390826146731;4888.095702295087;4937.654364194508;4944.33251511045;4952.158133117343;4957.355543779322;4960.751962401865;4969.428515720702;4985.326980857574;4912.852614421573;4914.336564563774;4919.11455937931;4923.8276660316715;4965.801367945278;5273.34083239595;5277.86805329866;5282.550983166436;5348.545373643245;5441.972790136064;5853.795117170878;5855.941440585582;5859.384764599698;5871.735771890275;5882.705864706791;5911;5926.4073592640825;5926.525877929756;5927.2590926134235;5927.703614819242;5351.09031464652;5378.91931621026;5403.162509810946;5440.591955603346;5472.72636368184;6018.15745795589;6025.6083354581815;6028.095785632103;6033.336333003366;6050.485708714808;5679.744411501492;5762.049261871828;5765.250517432791;5770.451807078321;5789.478946894799;5814.098814683731;5865.090910908816;5890.440408161217;5934.080217566284;5938.435848594377;5726.341470730836;5753.942460575425;5851.765929362778;5894.587378883522;5909.3204281507315;5798.33319168301;5813.447722466359;5844.766209351578;5865.325487568899;5888.705564721747;4201.873943781691;4230.746155230696;4277.2942464493635;4336.956630433719;4367.257566213688;3923.509561304837;3923.6861051115843;3932.9016774580864;3955.980220098881;3961.0493481564217;5621.072523033736;5644.350899646511;5668.178114173422;5668.461496157858;5669;5793.797217097382;5813.7383817728305;5836.9124463133085;5859.384764599741;5904.793332233351;5624.6625202487985;5627.240322556484;5627.409122042127;5629.296337957755;5642.125470552071;5008.373953255843;5010.073136469765;5012.022655582199;5012.924806127911;5053.36832895888;5957.165996760457;6019.969900150471;6020.09698545213;6094.360092190256;6145.600535965148;5900.380460051632;5909.734061967308;5921.61509501881;5924.170616113662;5945.256842894638;5948.10778383246;6109.144719739151;6117.510599152146;6122.204113465399;6141.385861413823;5724.799632013026;5751.4536119068625;5761.193432919311;5835.824925252249;5842.941570584239;5914.467697907209;5941.643501389757;5944.375840536682;5947.286325640951;5952.434518720715;5720.628159169641;5723.856903577277;5755.7409916553015;5792.739326730223;5804.810013947046;5594.580406469612;5716.142578613241;5752.712364381848;5787.536997040311;5865.237519122486;5666.631668941505;5703.885922281448;5714.599978001666;5717.342505611908;5728.79949201776;5653.123765816305;5699.401562835843;5810.970945145247;5835.44563266489;5860.443257890664;5592.514145361588;5650.378458369658;5661.66030038199;5662.745176466987;5667.0649342859615 22:25:01 INFO - PROCESS | 23598 | |1;ext.html;572.6220694341736;629.2386212682653;641.3458272561984;650.0703993289596;663.1048085085139;39597;43134.13731725363;43247.78376108119;43354.349684754714;43468.782656086754;43881.78059109708;44521.33218001733;44656;44781.55218447815;44802.551974480244;8561.101084386128;8677.1756683115;8715.738527844174;8733;8736.432131911437;8582.356323275748;8693.95653021734;8716.215540601344;8730.345224108185;8745.344099192554;8723.30213582915;8731.781705457352;8784.429012114193;8794.12058794122;8809.823803523926;8687.913120868774;8697.347698922544;8736.912630873674;8745.650173993032;8746.256568191711;8544.273736732384;8590.226879580805;8624.094470080614;8630.827383452359;8631.180037896389;40074.59925400738;40337.193256135004;40490.79754601238;40564.39153412701;40596;3457.550518432613;3519.4544845548726;3568.0544655666267;3583.4445660922597;3587.3722098632898;31480.37039259202;31998.360032799446;32356;32387.676123238703;32508;4402.207602631525;4473.692034766743;4514.774261286922;4578.450585929677;4601.746903920283;313956;321231;325075.9999999976;325146;326605;51515;55940.720296398256;58971.70514147402;59038.70480647569;59071.70464147652;61107.38892611017;61588;61691.383086168564;62070;62225;14701.117932924059;15022.699546009018;15117.395304187929;15120;15128.016678915836;11739.295642261492;11751.706207344885;11826.52693892252;11829.053675705924;11833.704157396134;17390.56523586895;17541.736873946902;17553.824461755474;17559.73660395095;17571.824281757017;5854.795082172103;5929.970350148222;5936.22829032223;6105.511559075263;6113.816585502442;1030.3766221436074;1044.0916402729522;1133.2747041893194;1135.1883403366633;1151.7638884028754;32141.51787723186;32718.67281327156;32777.83611081929;33013;33362.332753344795;1047.7747284333752;1095.8411030400548;1117.9161562882816;1153.1254983256251;1186.9881301186876;16369.83630163707;16610;16665.3333866644;16687.91656041736;16723.91638041802;254641;255760;255861;257383;535794;57121.714391428606;57162.714186429635;57310.42689573134;57441.13838292429;57533.71233143807;1333.6732500537237;1362.9318534073257;1363.6863521389937;1368.2474638948538;1371.7119404925077;29944.401111977204;32533.511997320533;33004.66995330016;33386.66613333835;35917;54796.72601636966;55571.444285558246;55796.72101639628;55899.720501397234;56023.439765603456;486627;498534;498904;503426;503685.926281465;27466.862665687342;27710.584341234495;27962.580561292005;28010.43979120447;28064.719352807027;47585;48493.51506485031;48540;48950.26574601312;49109.50890491049;41133.382999255635;41425;41679.374809377274;42063.579364205965;42091;40514;41062;41297.79351103225;41378.1724365505;41442.58557414387;40476.59523404728;40983.59016409917;42006.789966049975;42160.36759448549;44398.112037758416;34948.30103397969;37274.25451491009;37757;37782;38424.42363364496;27746.861265693544;27959;28650.14049578516;28653.856730717045;28897.42205155927 22:25:01 INFO - PROCESS | 23598 | |2;jquery.html;387.20236313194823;394.22141270989783;396.5320921312851;397.3503322068419;419.0675746464118;17936.19287132079;19082.61834763304;19725.50686232845;19824.30614928476;19843.503912402197;18378;18437.17032733527;18825.341113061044;19128.617427651472;19430;5455.863603409926;5542.140968149944;5720.2849643794525;5728.541716662678;5729.713514324267;5593.300837395325;5599.63602365847;5663.7168141592965;5669.6314739541795;5699.971500142494;5398.352197736277;5605.663660180402;5626.859328516779;5632.549396048306;5636.04187288162;5572.498475137217;5594.328680558318;5604.663720176803;5612.719364031782;5640.943590564103;4809.927851082237;4980.252962055684;4984.102861484931;4984.501549845023;5019;22215;22451.21420750266;22819.42951426211;23228.53542929149;23312.533749325088;2308.2959697292317;2411.794997425221;2486.5275597636573;2508.6487891694997;2516.5344411283972;9443.622255109787;14385;14605.196714180822;14634.487792927197;14873.776893346609;1913.473794706453;1945.7081437784445;1963.783983761785;2015.6841994918423;2032;83367;83573.58213208956;83883.5805820973;84090;85454;10213.846792298122;10464.267501274837;10765.693722511058;11117.27737697047;11185.105191584817;15284.847151528342;15321.923390382977;15326;15326.693466130615;15340.156291403857;7476.214997425192;8075.878861817077;8108.797280068045;8246.546439945741;8292.792680183044;7627.771166865003;7695.884561731578;7700.152983171846;7802.843943121219;7858.724944626911;4564.406627138451;4655.068986202706;4673.439187297546;4706.0823139487475;4731.29030645406;1791.5968906996081;1797.4248240563156;1816.5731053202487;1824.3705921457342;1828.7165489349172;902.4134312696799;913.365211178238;971.2666936462925;974.3033730882453;981.1954197558076;19026.238950441828;19452.8054719451;19656.3120290792;19690.803091969185;20182.89908550477;938.8028514012134;951.167728237792;952.7951490429591;963.1572374172599;1007.8992100789862;10761.843374156098;11003.834942475869;11010.348447732744;11066.170037247239;11110.167247142948;444362;448750;479952;488269;495227;85477;86879.56560217158;89636;94055;97486.51256743672;1048.7378155461136;1051.71603667011;1069.2782371898936;1075.295681328731;1077.0199118801852;17684.46946591604;18025.09874506243;18370.173342199367;18374.816251837845;18538.443846684622;43745.12509749851;43847.56152438562;44078.55921440745;44129.77935110432;44429.00138987507;57707.71146144242;58372.70813645905;61477;62255;63920.680396597716;7908.4464087513325;8621.852349529634;8664.48013119215;8867.467951922905;8884.600192991202;8829.870052098908;13315.267660278778;13548.72902541924;13560;13726.313684315945;11114.333140011626;11640.592579260025;12122.212056216318;12203.511859525875;12316.384180790745;11738.471768770254;11779;11928.463219155332;11931.701707457383;12071.939640301742;11955.103367247317;12203.267803931793;12228.388580570758;12399.938000309941;12749.362531873554;8713.477191368285;8737.868931966153;8815.118488151134;8906.510141942255;8962.283017358464;9097.59060842277;11922.76154476923;12292.938535307267;12335.75328493443;12347.13570050088 22:25:01 INFO - PROCESS | 23598 | |3;mootools.html;1451.8983671143017;1496.8877334199935;1635.2641311409861;1758.5955230297038;1790.695581751103;7844.529328240295;8512.276456501197;8520;9087.409318394308;9138.817223655531;8576.228139467446;8932.062133475973;8990.775230619238;8991.460512369262;9003.639854405832;2303.896324665394;2356.2931120663816;2363.5745565798147;2364.869932153731;2366.3019409274307;2299.8160147188187;2327.429779703975;2354.1878052072093;2361.8700971446565;2366.124533922451;2326.639370897505;2338.5206032763244;2343.3672908314743;2363.1492662641435;2363.161077817367;2311.711036120485;2322.6167682332393;2328.4062564046058;2355.4111472131967;2355.964660530085;2341.8477798943013;2355.364051706047;2367.9644805327935;2372.9406764830846;2376.6910301660846;9072.455652660861;9123.817523649555;9131.908680913173;9137.954310228406;9146.77133071672;5083.288339632454;5236.7119808410525;5279.5512381447625;5280.947190528084;5310;3112.673169317212;3305.6529064448127;3350.3634309481363;3351.8156501392414;3373.96626033739;1054.1040115901421;1059.348500672089;1082.8538147350087;1135.5911871726173;1135.982960255597;12432.937835310766;12485.438155283035;12551.686207844785;12608.495660173581;12616.81074783879;4502.459704835441;4540.455145382575;4648.48866624671;4681;4709.646776491777;10577.36535807854;10582.894171058344;10597.20520960931;10604.734881627866;10611.84082238767;5201.765920533585;5256.185291279858;5263.6578622389425;5266.4470230626;5269.341332333458;4392;4745.454272758608;4851.733154676526;4852.539008794159;4868.9756551217015;4060.403503208044;4078.5921407858978;4119.567445418248;4158.750474971512;4159.91680166395;1298.8701129887124;1303.9674008149873;1321.8744219299151;1327.7344531093627;1330.647378444713;953.5661274120258;962.3023308101571;967.8161149381594;981.1120935553336;988.3526290279879;6058.151858739692;6107.603005804608;6110.847228819315;6213.565050446426;6286.182796236551;452.0506935435559;452.35991072632515;455.79944824277214;456.2289730355688;459.3913065188638;4108.301588729923;4372.256716358227;4386.4736231652405;4391.275439552438;4443.377927090145;10601.41692206936;10698.465076746132;10909.509072091774;10929.781404371868;10942.890571094187;10810;11182;11206.495707693173;11227.999999999836;11237.83143252852;8101.270885620204;8164.387670924704;8214.83570328602;8247.711330103417;8260.586970651564;6195.907061394173;6471.514636402383;6487.253965793994;6578.76974305897;6579.342065793382;10138.797224055625;10313.381197128196;10358.533865975898;10443.68668939933;10455.84316235242;10473.581056757948;10485.475726213812;10491;10497.10774584169;10506;7797.181295964069;7898.328642065259;7963.761087167394;8171.591420429074;8179.877301840358;9113.134252246025;9116;9119.589618467053;9120.543972801466;9145.86281205769;7723.684014888304;8022.438429309892;8068.87896681562;8141.389395795458;8149.755507334789;7579.469437139347;7723.189065148079;7776.300132988058;7880.645370958206;7922.0889597694695;7222.313880181375;7338.302861228175;7443.404527637667;7449.180590135189;7464.402847772057;5238.319018527567;5357.142857142839;5425.891482170414;5460.563154947516;5515.4760297771645;5258.710770907637;5369.1140961740875;5426.565874729933;5454.400015998158;5459.454054594509 22:25:01 INFO - PROCESS | 23598 | |4;prototype.html;326.6537470281502;332.7389194951135;336.14451221640905;338.98474568644406;339.30611898667223;5539.224508568798;5596.300462442195;5607.439256074391;5616.9157462637995;5678.744456499458;5503.367112782032;5522.502974732273;5534.446555344463;5562.527185189264;5569.192467092269;4199.3281075028;4304.2137512932095;4320.956790432103;4324.827006919735;4333.891652708692;4274.957250427487;4287.735118140156;4296.699231053827;4313.482382114151;4333.826646934134;4239.194553034913;4268.634037108111;4286.549912259215;4288.742675439484;4307.0739790944945;4069.837206511736;4165.604267594574;4171.666266698672;4190.643795277405;4196.433481479991;3684.44733290006;3688.391415416453;3713.16453797896;3726.795026273554;3745.513083299181;5393.83818485446;5401.729913504349;5413;5477;5483.341998960111;4848.903021939542;4877.926831097537;4929.137400954819;4936.925946110811;4938.950610493885;5828.388019257959;5837.795677151322;5856.824295271149;5861.384554621746;5864;4135.627793498599;4145.191687620915;4152.6055024772595;4157.854475093357;4172.248995180867;5901.527877769769;6060.787872424444;6075.453209211192;6078.240219972504;6106.236720409949;4143.4199212110325;4172.685604034719;4205.074883525619;4215.810288536962;4216.177845320194;4216.683748718859;4240.745555266632;4242.193983143193;4258.808353624095;4292.720973136736;4627;4879.487653796371;4898.79979405048;4914.975425122851;4922.72924989129;5854.151148083504;5862.560307976833;5865;5912.17229587858;5922.200502932135;1460.4450308882556;1476.5717941796759;1500.6023403798001;1501.144347721788;1506.5254444849836;933.5285680731323;950.8573713942964;954.9352471993677;956.7751578379075;957.8362289817766;5102.4897510248675;5118.59051275897;5210.769323019676;5210.843674689765;5238.109521381298;5459.672419654833;5464.972675136599;5477.589180811455;5485.588580856452;5535.533083732734;4778.259369797687;5209.166533354645;5210.098314115321;5236.423993360727;5247.606429517801;2878.913632591026;2928.169893816365;2931.6628587712257;2941.8823247070304;2957.8521073946217;5250.3962044364625;5584.106542953108;5598.356189038232;5620.213170156181;5721.77112915487;5360.437154098763;5417.458254174551;5435.402105768362;5440.863978400572;5452.727363631802;5510.9448905511235;5536.640118392372;5555.972220138873;5595.832125036255;5597.5295316903785;5222.686638801684;5286.788528458818;5315.6279060465395;5342.9465705342445;5365.758540865749;5321.467853214803;5339.4660533947845;5365.624406291522;5405.594580406407;5437.341610808741;5467.753951072132;5479.876470411987;5521.5306698931045;5545.532238154144;5665.830025099253;3699.426588878728;3910.1984093260244;3910.8240129193687;3919.3729003359326;3934.370500719871;5419.403865574863;5480.712032672239;5484.217629270583;5527.226188333717;5547.916781248366;5229.326615483009;5253.474652534716;5280;5281.260623512634;5299.641659461503;5054.898902021865;5199.506046925536;5219.739013049409;5237.790488380422;5301.522862942354;5150.587952963829;5151.2273159026445;5604.971975140098;5649.915251271316;5824.359320474659;5885;5886.764529418947;5894.469497745224;5902.38025007368;5937.762489500372;5582.7766889323975;5751.827445176651;5782.277215348082;5800.477956983892;5809.983252930805 22:25:01 INFO - PROCESS | 23598 | |5;yui.html;734.6987735028636;744.5123444144081;785.3089281432339;804.919508049195;852.9999999999998;12580.559680411188;13831.170129792206;13832.723345533082;13997.720045599082;14107.78838317426;13386.397612107454;13865.168089914612;13873.16760994341;13878.653033674138;13884.861151388508;809.7489778168757;818.7953011747063;821.2813787935556;826.4297634632114;830.3232865214861;807.2049031703767;812.7114874219656;813.4915677701437;814.450246083894;815.0748899998486;865.4980111535294;869.6434461870634;871;871.5337294547419;871.7341210930664;822.2558584481054;857.8798968144465;859.7463748194298;859.7807559072459;862.6894318045498;608.2002167150183;608.7230310208845;609.9512039036911;612.6569121292091;614.7325913227716;14088.577342679737;14170.433182672681;14172;14177.291135443187;14199.71600567983;14066.367013484316;14518.854811451962;14585.562433126917;14623.780643290253;14676;12271.325077120755;12298.20061695987;12299.569515066929;12306.138570300083;12368.505259789597;3869.419587061935;3875.476810630557;3900.6294402031763;3925.450436938831;4017.0961533654986;60359;66201.33798662;66580.99999999952;66625.33374666289;66711.33288667099;10748.838767418494;10757.246992710592;10966.177536684781;11099.83350249747;11424.257423267461;20623.896880515502;20654.483637909172;20808.167673293203;20894.686579701316;21087.8945605271;8351.916480835114;8375.748727538183;8435.451695639764;8462.534560599226;8475.364347673949;4519.88700282489;4538.387317712067;4538.841140560065;4603.976980115078;4638.652101092432;3319.2697606526526;3416.1630400552026;3422.5208470814105;3435.1927297085167;3451.0682115828586;592.7362323765883;600.072887388985;607.9088136779518;613.6072913335469;614.6373639552685;651.6513665189101;687.3298533929379;737.6053811210976;777.5723352156291;779.8557266905527;9262.305327100494;9413.529323533796;9687.225021998363;9744.707658770249;9755;593.9227900372924;672.351181110231;715.059696499102;716.9426445884317;722.1262272650152;4868.172410690191;4939.2838038484215;4980.601551875914;4988.800447982113;4990.525900039491;72597;75136;75161;75375;75787;66988;67463;67563;67892.00808048646;68268.6586567064;62385;62966.37033629697;63214;63342.36657633365;63941.68029159917;9375.85936210971;9566.378185417925;9616.75958101025;9618.711438656852;9716;36559.26881462303;37724.81137594295;38462.807685961394;40018;40472.79763601163;49199.26201106915;49404.75297623633;49528.50471495239;50238.74880625573;55867;12503.37483125822;13098.803517947048;13100.60698179056;13375.123361584629;13965.790513142514;13994.790078149039;14097.647558810702;14272.928635356757;14521.564353069425;14885.404583816526;11235.606753763906;11865.996417175475;11918.52325906954;12328.583818609837;12625.936870315589;13702;13832.930835345758;13970.860291396955;14256.572302830931;14691.191984440959;13113.213207207598;13184.27486488214;13193;13242.57980352229;13708.45166193381;10053.447060411456;10064.748381290527;10548.208884333551;10583.4708264588;10918;11146.88853111491;11209.327440353605;11294.265872718248;11311.434428278717;11624.186306958432 22:25:01 INFO - PROCESS | 23598 | __end_tp_report 22:25:01 INFO - PROCESS | 23598 | __start_cc_report 22:25:01 INFO - PROCESS | 23598 | _x_x_mozilla_cycle_collect,360 22:25:01 INFO - PROCESS | 23598 | __end_cc_report 22:25:01 INFO - PROCESS | 23598 | __startTimestamp1464758701855__endTimestamp 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | ------- Summary: start ------- 22:25:01 INFO - PROCESS | 23598 | Number of tests: 6 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#0] dojo.html Cycles:165 Average:5438.54 Median:5669.00 stddev:644.04 (11.4%) stddev-sans-first:620.47 22:25:01 INFO - PROCESS | 23598 | Values: 3149.1 3155.6 3156.6 3184.6 3202.8 5376.2 5404.6 5445.1 5483.9 5534.5 5280.4 5373.2 5427.1 5483.5 5503.1 4848.3 4872.5 4968.9 5023.5 5065.5 4881.9 5088.2 5095.8 5106.4 5121.1 4827.9 4873.4 4888.1 4937.7 4944.3 4952.2 4957.4 4960.8 4969.4 4985.3 4912.9 4914.3 4919.1 4923.8 4965.8 5273.3 5277.9 5282.6 5348.5 5442.0 5853.8 5855.9 5859.4 5871.7 5882.7 5911.0 5926.4 5926.5 5927.3 5927.7 5351.1 5378.9 5403.2 5440.6 5472.7 6018.2 6025.6 6028.1 6033.3 6050.5 5679.7 5762.0 5765.3 5770.5 5789.5 5814.1 5865.1 5890.4 5934.1 5938.4 5726.3 5753.9 5851.8 5894.6 5909.3 5798.3 5813.4 5844.8 5865.3 5888.7 4201.9 4230.7 4277.3 4337.0 4367.3 3923.5 3923.7 3932.9 3956.0 3961.0 5621.1 5644.4 5668.2 5668.5 5669.0 5793.8 5813.7 5836.9 5859.4 5904.8 5624.7 5627.2 5627.4 5629.3 5642.1 5008.4 5010.1 5012.0 5012.9 5053.4 5957.2 6020.0 6020.1 6094.4 6145.6 5900.4 5909.7 5921.6 5924.2 5945.3 5948.1 6109.1 6117.5 6122.2 6141.4 5724.8 5751.5 5761.2 5835.8 5842.9 5914.5 5941.6 5944.4 5947.3 5952.4 5720.6 5723.9 5755.7 5792.7 5804.8 5594.6 5716.1 5752.7 5787.5 5865.2 5666.6 5703.9 5714.6 5717.3 5728.8 5653.1 5699.4 5811.0 5835.4 5860.4 5592.5 5650.4 5661.7 5662.7 5667.1 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#1] ext.html Cycles:180 Average:54972.44 Median:41337.98 stddev:104004.40 (251.6%) stddev-sans-first:104215.95 22:25:01 INFO - PROCESS | 23598 | Values: 572.6 629.2 641.3 650.1 663.1 39597.0 43134.1 43247.8 43354.3 43468.8 43881.8 44521.3 44656.0 44781.6 44802.6 8561.1 8677.2 8715.7 8733.0 8736.4 8582.4 8694.0 8716.2 8730.3 8745.3 8723.3 8731.8 8784.4 8794.1 8809.8 8687.9 8697.3 8736.9 8745.7 8746.3 8544.3 8590.2 8624.1 8630.8 8631.2 40074.6 40337.2 40490.8 40564.4 40596.0 3457.6 3519.5 3568.1 3583.4 3587.4 31480.4 31998.4 32356.0 32387.7 32508.0 4402.2 4473.7 4514.8 4578.5 4601.7 313956.0 321231.0 325076.0 325146.0 326605.0 51515.0 55940.7 58971.7 59038.7 59071.7 61107.4 61588.0 61691.4 62070.0 62225.0 14701.1 15022.7 15117.4 15120.0 15128.0 11739.3 11751.7 11826.5 11829.1 11833.7 17390.6 17541.7 17553.8 17559.7 17571.8 5854.8 5930.0 5936.2 6105.5 6113.8 1030.4 1044.1 1133.3 1135.2 1151.8 32141.5 32718.7 32777.8 33013.0 33362.3 1047.8 1095.8 1117.9 1153.1 1187.0 16369.8 16610.0 16665.3 16687.9 16723.9 254641.0 255760.0 255861.0 257383.0 535794.0 57121.7 57162.7 57310.4 57441.1 57533.7 1333.7 1362.9 1363.7 1368.2 1371.7 29944.4 32533.5 33004.7 33386.7 35917.0 54796.7 55571.4 55796.7 55899.7 56023.4 486627.0 498534.0 498904.0 503426.0 503685.9 27466.9 27710.6 27962.6 28010.4 28064.7 47585.0 48493.5 48540.0 48950.3 49109.5 41133.4 41425.0 41679.4 42063.6 42091.0 40514.0 41062.0 41297.8 41378.2 41442.6 40476.6 40983.6 42006.8 42160.4 44398.1 34948.3 37274.3 37757.0 37782.0 38424.4 27746.9 27959.0 28650.1 28653.9 28897.4 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#2] jquery.html Cycles:180 Average:28761.68 Median:396.94 stddev:77956.15 (19639.2%) stddev-sans-first:78145.72 22:25:01 INFO - PROCESS | 23598 | Values: 387.2 394.2 396.5 397.4 419.1 17936.2 19082.6 19725.5 19824.3 19843.5 18378.0 18437.2 18825.3 19128.6 19430.0 5455.9 5542.1 5720.3 5728.5 5729.7 5593.3 5599.6 5663.7 5669.6 5700.0 5398.4 5605.7 5626.9 5632.5 5636.0 5572.5 5594.3 5604.7 5612.7 5640.9 4809.9 4980.3 4984.1 4984.5 5019.0 22215.0 22451.2 22819.4 23228.5 23312.5 2308.3 2411.8 2486.5 2508.6 2516.5 9443.6 14385.0 14605.2 14634.5 14873.8 1913.5 1945.7 1963.8 2015.7 2032.0 83367.0 83573.6 83883.6 84090.0 85454.0 10213.8 10464.3 10765.7 11117.3 11185.1 15284.8 15321.9 15326.0 15326.7 15340.2 7476.2 8075.9 8108.8 8246.5 8292.8 7627.8 7695.9 7700.2 7802.8 7858.7 4564.4 4655.1 4673.4 4706.1 4731.3 1791.6 1797.4 1816.6 1824.4 1828.7 902.4 913.4 971.3 974.3 981.2 19026.2 19452.8 19656.3 19690.8 20182.9 938.8 951.2 952.8 963.2 1007.9 10761.8 11003.8 11010.3 11066.2 11110.2 444362.0 448750.0 479952.0 488269.0 495227.0 85477.0 86879.6 89636.0 94055.0 97486.5 1048.7 1051.7 1069.3 1075.3 1077.0 17684.5 18025.1 18370.2 18374.8 18538.4 43745.1 43847.6 44078.6 44129.8 44429.0 57707.7 58372.7 61477.0 62255.0 63920.7 7908.4 8621.9 8664.5 8867.5 8884.6 8829.9 13315.3 13548.7 13560.0 13726.3 11114.3 11640.6 12122.2 12203.5 12316.4 11738.5 11779.0 11928.5 11931.7 12071.9 11955.1 12203.3 12228.4 12399.9 12749.4 8713.5 8737.9 8815.1 8906.5 8962.3 9097.6 11922.8 12292.9 12335.8 12347.1 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#3] mootools.html Cycles:180 Average:5924.33 Median:456.01 stddev:3394.87 (744.5%) stddev-sans-first:3387.76 22:25:01 INFO - PROCESS | 23598 | Values: 1451.9 1496.9 1635.3 1758.6 1790.7 7844.5 8512.3 8520.0 9087.4 9138.8 8576.2 8932.1 8990.8 8991.5 9003.6 2303.9 2356.3 2363.6 2364.9 2366.3 2299.8 2327.4 2354.2 2361.9 2366.1 2326.6 2338.5 2343.4 2363.1 2363.2 2311.7 2322.6 2328.4 2355.4 2356.0 2341.8 2355.4 2368.0 2372.9 2376.7 9072.5 9123.8 9131.9 9138.0 9146.8 5083.3 5236.7 5279.6 5280.9 5310.0 3112.7 3305.7 3350.4 3351.8 3374.0 1054.1 1059.3 1082.9 1135.6 1136.0 12432.9 12485.4 12551.7 12608.5 12616.8 4502.5 4540.5 4648.5 4681.0 4709.6 10577.4 10582.9 10597.2 10604.7 10611.8 5201.8 5256.2 5263.7 5266.4 5269.3 4392.0 4745.5 4851.7 4852.5 4869.0 4060.4 4078.6 4119.6 4158.8 4159.9 1298.9 1304.0 1321.9 1327.7 1330.6 953.6 962.3 967.8 981.1 988.4 6058.2 6107.6 6110.8 6213.6 6286.2 452.1 452.4 455.8 456.2 459.4 4108.3 4372.3 4386.5 4391.3 4443.4 10601.4 10698.5 10909.5 10929.8 10942.9 10810.0 11182.0 11206.5 11228.0 11237.8 8101.3 8164.4 8214.8 8247.7 8260.6 6195.9 6471.5 6487.3 6578.8 6579.3 10138.8 10313.4 10358.5 10443.7 10455.8 10473.6 10485.5 10491.0 10497.1 10506.0 7797.2 7898.3 7963.8 8171.6 8179.9 9113.1 9116.0 9119.6 9120.5 9145.9 7723.7 8022.4 8068.9 8141.4 8149.8 7579.5 7723.2 7776.3 7880.6 7922.1 7222.3 7338.3 7443.4 7449.2 7464.4 5238.3 5357.1 5425.9 5460.6 5515.5 5258.7 5369.1 5426.6 5454.4 5459.5 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#4] prototype.html Cycles:180 Average:4685.15 Median:5280.63 stddev:1352.82 (25.6%) stddev-sans-first:1316.46 22:25:01 INFO - PROCESS | 23598 | Values: 326.7 332.7 336.1 339.0 339.3 5539.2 5596.3 5607.4 5616.9 5678.7 5503.4 5522.5 5534.4 5562.5 5569.2 4199.3 4304.2 4321.0 4324.8 4333.9 4275.0 4287.7 4296.7 4313.5 4333.8 4239.2 4268.6 4286.5 4288.7 4307.1 4069.8 4165.6 4171.7 4190.6 4196.4 3684.4 3688.4 3713.2 3726.8 3745.5 5393.8 5401.7 5413.0 5477.0 5483.3 4848.9 4877.9 4929.1 4936.9 4939.0 5828.4 5837.8 5856.8 5861.4 5864.0 4135.6 4145.2 4152.6 4157.9 4172.2 5901.5 6060.8 6075.5 6078.2 6106.2 4143.4 4172.7 4205.1 4215.8 4216.2 4216.7 4240.7 4242.2 4258.8 4292.7 4627.0 4879.5 4898.8 4915.0 4922.7 5854.2 5862.6 5865.0 5912.2 5922.2 1460.4 1476.6 1500.6 1501.1 1506.5 933.5 950.9 954.9 956.8 957.8 5102.5 5118.6 5210.8 5210.8 5238.1 5459.7 5465.0 5477.6 5485.6 5535.5 4778.3 5209.2 5210.1 5236.4 5247.6 2878.9 2928.2 2931.7 2941.9 2957.9 5250.4 5584.1 5598.4 5620.2 5721.8 5360.4 5417.5 5435.4 5440.9 5452.7 5510.9 5536.6 5556.0 5595.8 5597.5 5222.7 5286.8 5315.6 5342.9 5365.8 5321.5 5339.5 5365.6 5405.6 5437.3 5467.8 5479.9 5521.5 5545.5 5665.8 3699.4 3910.2 3910.8 3919.4 3934.4 5419.4 5480.7 5484.2 5527.2 5547.9 5229.3 5253.5 5280.0 5281.3 5299.6 5054.9 5199.5 5219.7 5237.8 5301.5 5150.6 5151.2 5605.0 5649.9 5824.4 5885.0 5886.8 5894.5 5902.4 5937.8 5582.8 5751.8 5782.3 5800.5 5810.0 22:25:01 INFO - PROCESS | 23598 | 22:25:01 INFO - PROCESS | 23598 | [#5] yui.html Cycles:180 Average:16680.77 Median:27033.72 stddev:20785.17 (76.9%) stddev-sans-first:20808.98 22:25:01 INFO - PROCESS | 23598 | Values: 734.7 744.5 785.3 804.9 853.0 12580.6 13831.2 13832.7 13997.7 14107.8 13386.4 13865.2 13873.2 13878.7 13884.9 809.7 818.8 821.3 826.4 830.3 807.2 812.7 813.5 814.5 815.1 865.5 869.6 871.0 871.5 871.7 822.3 857.9 859.7 859.8 862.7 608.2 608.7 610.0 612.7 614.7 14088.6 14170.4 14172.0 14177.3 14199.7 14066.4 14518.9 14585.6 14623.8 14676.0 12271.3 12298.2 12299.6 12306.1 12368.5 3869.4 3875.5 3900.6 3925.5 4017.1 60359.0 66201.3 66581.0 66625.3 66711.3 10748.8 10757.2 10966.2 11099.8 11424.3 20623.9 20654.5 20808.2 20894.7 21087.9 8351.9 8375.7 8435.5 8462.5 8475.4 4519.9 4538.4 4538.8 4604.0 4638.7 3319.3 3416.2 3422.5 3435.2 3451.1 592.7 600.1 607.9 613.6 614.6 651.7 687.3 737.6 777.6 779.9 9262.3 9413.5 9687.2 9744.7 9755.0 593.9 672.4 715.1 716.9 722.1 4868.2 4939.3 4980.6 4988.8 4990.5 72597.0 75136.0 75161.0 75375.0 75787.0 66988.0 67463.0 67563.0 67892.0 68268.7 62385.0 62966.4 63214.0 63342.4 63941.7 9375.9 9566.4 9616.8 9618.7 9716.0 36559.3 37724.8 38462.8 40018.0 40472.8 49199.3 49404.8 49528.5 50238.7 55867.0 12503.4 13098.8 13100.6 13375.1 13965.8 13994.8 14097.6 14272.9 14521.6 14885.4 11235.6 11866.0 11918.5 12328.6 12625.9 13702.0 13832.9 13970.9 14256.6 14691.2 13113.2 13184.3 13193.0 13242.6 13708.5 10053.4 10064.7 10548.2 10583.5 10918.0 11146.9 11209.3 11294.3 11311.4 11624.2 22:25:01 INFO - PROCESS | 23598 | -------- Summary: end -------- 22:25:01 INFO - PROCESS | 23598 | 22:25:02 INFO - TEST-INFO | 23598: exit 0 22:25:02 INFO - TEST-OK | dromaeo_css | took 1111109ms 22:25:02 INFO - TEST-START | kraken 22:25:02 INFO - operating with platform_type : linux_ 22:25:02 INFO - Initialising browser for kraken test... 22:25:02 INFO - TEST-INFO | started process 23835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpELQSSY/profile http://localhost:45846/getInfo.html) 22:25:07 INFO - PROCESS | 23835 | __metrics Screen width/height:1600/1200 22:25:07 INFO - PROCESS | 23835 | colorDepth:24 22:25:07 INFO - PROCESS | 23835 | Browser inner width/height: 1024/697 22:25:07 INFO - PROCESS | 23835 | __metrics 22:25:08 ERROR - PROCESS | 23835 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:25:08 INFO - TEST-INFO | 23835: exit 0 22:25:08 INFO - Browser initialized. 22:25:08 INFO - Running cycle 1/1 for kraken test... 22:25:08 INFO - TEST-INFO | started process 23956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpELQSSY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:20 INFO - PROCESS | 23956 | RSS: Main: 166584320 22:25:20 INFO - PROCESS | 23956 | 22:25:23 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45846/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:24 INFO - PROCESS | 23956 | RSS: Main: 171728896 22:25:24 INFO - PROCESS | 23956 | 22:25:26 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45846/tests/kraken/driver.html?testName=audio-dft) 22:25:27 INFO - PROCESS | 23956 | RSS: Main: 171855872 22:25:27 INFO - PROCESS | 23956 | 22:25:30 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45846/tests/kraken/driver.html?testName=audio-fft) 22:25:30 INFO - PROCESS | 23956 | RSS: Main: 166371328 22:25:30 INFO - PROCESS | 23956 | 22:25:32 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45846/tests/kraken/driver.html?testName=audio-oscillator) 22:25:33 INFO - PROCESS | 23956 | RSS: Main: 167378944 22:25:33 INFO - PROCESS | 23956 | 22:25:35 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45846/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:25:36 INFO - PROCESS | 23956 | RSS: Main: 167677952 22:25:36 INFO - PROCESS | 23956 | 22:25:39 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45846/tests/kraken/driver.html?testName=imaging-darkroom) 22:25:40 INFO - PROCESS | 23956 | RSS: Main: 167428096 22:25:40 INFO - PROCESS | 23956 | 22:25:43 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45846/tests/kraken/driver.html?testName=imaging-desaturate) 22:25:44 INFO - PROCESS | 23956 | RSS: Main: 168194048 22:25:44 INFO - PROCESS | 23956 | 22:25:46 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45846/tests/kraken/driver.html?testName=json-parse-financial) 22:25:47 INFO - PROCESS | 23956 | RSS: Main: 168124416 22:25:47 INFO - PROCESS | 23956 | 22:25:49 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45846/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:25:49 INFO - PROCESS | 23956 | RSS: Main: 167874560 22:25:49 INFO - PROCESS | 23956 | 22:25:51 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:25:52 INFO - PROCESS | 23956 | RSS: Main: 167829504 22:25:52 INFO - PROCESS | 23956 | 22:25:54 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:25:54 INFO - PROCESS | 23956 | RSS: Main: 168116224 22:25:54 INFO - PROCESS | 23956 | 22:25:57 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:25:58 INFO - PROCESS | 23956 | RSS: Main: 168198144 22:25:58 INFO - PROCESS | 23956 | 22:26:00 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:01 INFO - PROCESS | 23956 | RSS: Main: 168218624 22:26:01 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | Cycle 1(1): loaded http://localhost:45846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45846/tests/kraken/driver.html?testName=ai-astar) 22:26:03 INFO - PROCESS | 23956 | RSS: Main: 169291776 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | __start_tp_report 22:26:03 INFO - PROCESS | 23956 | _x_x_mozilla_page_load 22:26:03 INFO - PROCESS | 23956 | _x_x_mozilla_page_load_details 22:26:03 INFO - PROCESS | 23956 | |i|pagename|runs| 22:26:03 INFO - PROCESS | 23956 | |0;ai-astar;113;105;112;110;112;111;110;110;110;110 22:26:03 INFO - PROCESS | 23956 | |1;audio-beat-detection;163;126;149;176;126;158;159;128;196;136 22:26:03 INFO - PROCESS | 23956 | |2;audio-dft;164;160;164;161;163;163;178;162;161;163 22:26:03 INFO - PROCESS | 23956 | |3;audio-fft;75;74;75;148;77;75;75;73;170;77 22:26:03 INFO - PROCESS | 23956 | |4;audio-oscillator;85;88;151;83;82;83;138;83;82;132 22:26:03 INFO - PROCESS | 23956 | |5;imaging-gaussian-blur;117;117;118;117;117;120;118;117;117;117 22:26:03 INFO - PROCESS | 23956 | |6;imaging-darkroom;147;147;147;146;146;146;147;147;147;147 22:26:03 INFO - PROCESS | 23956 | |7;imaging-desaturate;94;96;96;96;95;95;96;96;95;95 22:26:03 INFO - PROCESS | 23956 | |8;json-parse-financial;55;55;55;53;55;53;54;54;55;54 22:26:03 INFO - PROCESS | 23956 | |9;json-stringify-tinderbox;62;64;61;62;62;62;62;62;63;62 22:26:03 INFO - PROCESS | 23956 | |10;stanford-crypto-aes;79;82;89;81;80;82;80;80;112;81 22:26:03 INFO - PROCESS | 23956 | |11;stanford-crypto-ccm;121;133;126;120;130;138;127;133;134;123 22:26:03 INFO - PROCESS | 23956 | |12;stanford-crypto-pbkdf2;164;161;158;163;169;163;159;157;164;159 22:26:03 INFO - PROCESS | 23956 | |13;stanford-crypto-sha256-iterative;62;64;64;64;68;64;61;63;61;61 22:26:03 INFO - PROCESS | 23956 | __end_tp_report 22:26:03 INFO - PROCESS | 23956 | __start_cc_report 22:26:03 INFO - PROCESS | 23956 | _x_x_mozilla_cycle_collect,872 22:26:03 INFO - PROCESS | 23956 | __end_cc_report 22:26:03 INFO - PROCESS | 23956 | __startTimestamp1464758763418__endTimestamp 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | ------- Summary: start ------- 22:26:03 INFO - PROCESS | 23956 | Number of tests: 14 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.50 stddev:2.16 (2.0%) stddev-sans-first:2.06 22:26:03 INFO - PROCESS | 23956 | Values: 113.0 105.0 112.0 110.0 112.0 111.0 110.0 110.0 110.0 110.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:158.50 stddev:23.36 (14.7%) stddev-sans-first:24.41 22:26:03 INFO - PROCESS | 23956 | Values: 163.0 126.0 149.0 176.0 126.0 158.0 159.0 128.0 196.0 136.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.00 stddev:5.13 (3.1%) stddev-sans-first:5.44 22:26:03 INFO - PROCESS | 23956 | Values: 164.0 160.0 164.0 161.0 163.0 163.0 178.0 162.0 161.0 163.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#3] audio-fft Cycles:10 Average:91.90 Median:75.00 stddev:35.76 (47.7%) stddev-sans-first:37.41 22:26:03 INFO - PROCESS | 23956 | Values: 75.0 74.0 75.0 148.0 77.0 75.0 75.0 73.0 170.0 77.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#4] audio-oscillator Cycles:10 Average:100.70 Median:83.00 stddev:27.79 (33.5%) stddev-sans-first:28.88 22:26:03 INFO - PROCESS | 23956 | Values: 85.0 88.0 151.0 83.0 82.0 83.0 138.0 83.0 82.0 132.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.00 stddev:0.97 (0.8%) stddev-sans-first:1.01 22:26:03 INFO - PROCESS | 23956 | Values: 117.0 117.0 118.0 117.0 117.0 120.0 118.0 117.0 117.0 117.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:26:03 INFO - PROCESS | 23956 | Values: 147.0 147.0 147.0 146.0 146.0 146.0 147.0 147.0 147.0 147.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 22:26:03 INFO - PROCESS | 23956 | Values: 94.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 22:26:03 INFO - PROCESS | 23956 | Values: 55.0 55.0 55.0 53.0 55.0 53.0 54.0 54.0 55.0 54.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.83 22:26:03 INFO - PROCESS | 23956 | Values: 62.0 64.0 61.0 62.0 62.0 62.0 62.0 62.0 63.0 62.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:81.00 stddev:10.02 (12.4%) stddev-sans-first:10.43 22:26:03 INFO - PROCESS | 23956 | Values: 79.0 82.0 89.0 81.0 80.0 82.0 80.0 80.0 112.0 81.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:131.50 stddev:6.06 (4.6%) stddev-sans-first:5.79 22:26:03 INFO - PROCESS | 23956 | Values: 121.0 133.0 126.0 120.0 130.0 138.0 127.0 133.0 134.0 123.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.70 Median:163.00 stddev:3.62 (2.2%) stddev-sans-first:3.75 22:26:03 INFO - PROCESS | 23956 | Values: 164.0 161.0 158.0 163.0 169.0 163.0 159.0 157.0 164.0 159.0 22:26:03 INFO - PROCESS | 23956 | 22:26:03 INFO - PROCESS | 23956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:64.00 stddev:2.15 (3.4%) stddev-sans-first:2.24 22:26:03 INFO - PROCESS | 23956 | Values: 62.0 64.0 64.0 64.0 68.0 64.0 61.0 63.0 61.0 61.0 22:26:03 INFO - PROCESS | 23956 | -------- Summary: end -------- 22:26:03 INFO - PROCESS | 23956 | 22:26:04 INFO - TEST-INFO | 23956: exit 0 22:26:04 INFO - TEST-OK | kraken | took 61844ms 22:26:04 INFO - SUITE-END | took 1172s 22:26:04 INFO - Completed test suite (00:19:33) 22:26:04 INFO - javascript benchmark 22:26:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7185.57593787527, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3149.11824689087, 3155.6371017333013, 3156.5580818685376, 3184.633767116782, 3202.791818531796, 5376.166694162406, 5404.648697834638, 5445.128779395298, 5483.890322193553, 5534.501894829465, 5280.392754833186, 5373.167159090338, 5427.050266203419, 5483.533899618537, 5503.147012213103, 4848.272759086139, 4872.4640289568115, 4968.900621987558, 5023.522765337287, 5065.468125846788, 4881.926771098437, 5088.211327244283, 5095.770690318926, 5106.387233531981, 5121.12940800063, 4827.927581086286, 4873.390826146731, 4888.095702295087, 4937.654364194508, 4944.33251511045, 4952.158133117343, 4957.355543779322, 4960.751962401865, 4969.428515720702, 4985.326980857574, 4912.852614421573, 4914.336564563774, 4919.11455937931, 4923.8276660316715, 4965.801367945278, 5273.34083239595, 5277.86805329866, 5282.550983166436, 5348.545373643245, 5441.972790136064, 5853.795117170878, 5855.941440585582, 5859.384764599698, 5871.735771890275, 5882.705864706791, 5911.0, 5926.4073592640825, 5926.525877929756, 5927.2590926134235, 5927.703614819242, 5351.09031464652, 5378.91931621026, 5403.162509810946, 5440.591955603346, 5472.72636368184, 6018.15745795589, 6025.6083354581815, 6028.095785632103, 6033.336333003366, 6050.485708714808, 5679.744411501492, 5762.049261871828, 5765.250517432791, 5770.451807078321, 5789.478946894799, 5814.098814683731, 5865.090910908816, 5890.440408161217, 5934.080217566284, 5938.435848594377, 5726.341470730836, 5753.942460575425, 5851.765929362778, 5894.587378883522, 5909.3204281507315, 5798.33319168301, 5813.447722466359, 5844.766209351578, 5865.325487568899, 5888.705564721747, 4201.873943781691, 4230.746155230696, 4277.2942464493635, 4336.956630433719, 4367.257566213688, 3923.509561304837, 3923.6861051115843, 3932.9016774580864, 3955.980220098881, 3961.0493481564217, 5621.072523033736, 5644.350899646511, 5668.178114173422, 5668.461496157858, 5669.0, 5793.797217097382, 5813.7383817728305, 5836.9124463133085, 5859.384764599741, 5904.793332233351, 5624.6625202487985, 5627.240322556484, 5627.409122042127, 5629.296337957755, 5642.125470552071, 5008.373953255843, 5010.073136469765, 5012.022655582199, 5012.924806127911, 5053.36832895888, 5957.165996760457, 6019.969900150471, 6020.09698545213, 6094.360092190256, 6145.600535965148, 5900.380460051632, 5909.734061967308, 5921.61509501881, 5924.170616113662, 5945.256842894638, 5948.10778383246, 6109.144719739151, 6117.510599152146, 6122.204113465399, 6141.385861413823, 5724.799632013026, 5751.4536119068625, 5761.193432919311, 5835.824925252249, 5842.941570584239, 5914.467697907209, 5941.643501389757, 5944.375840536682, 5947.286325640951, 5952.434518720715, 5720.628159169641, 5723.856903577277, 5755.7409916553015, 5792.739326730223, 5804.810013947046, 5594.580406469612, 5716.142578613241, 5752.712364381848, 5787.536997040311, 5865.237519122486, 5666.631668941505, 5703.885922281448, 5714.599978001666, 5717.342505611908, 5728.79949201776, 5653.123765816305, 5699.401562835843, 5810.970945145247, 5835.44563266489, 5860.443257890664, 5592.514145361588, 5650.378458369658, 5661.66030038199, 5662.745176466987, 5667.0649342859615], "value": 5393.38245295616, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.6220694341736, 629.2386212682653, 641.3458272561984, 650.0703993289596, 663.1048085085139, 39597.0, 43134.13731725363, 43247.78376108119, 43354.349684754714, 43468.782656086754, 43881.78059109708, 44521.33218001733, 44656.0, 44781.55218447815, 44802.551974480244, 8561.101084386128, 8677.1756683115, 8715.738527844174, 8733.0, 8736.432131911437, 8582.356323275748, 8693.95653021734, 8716.215540601344, 8730.345224108185, 8745.344099192554, 8723.30213582915, 8731.781705457352, 8784.429012114193, 8794.12058794122, 8809.823803523926, 8687.913120868774, 8697.347698922544, 8736.912630873674, 8745.650173993032, 8746.256568191711, 8544.273736732384, 8590.226879580805, 8624.094470080614, 8630.827383452359, 8631.180037896389, 40074.59925400738, 40337.193256135004, 40490.79754601238, 40564.39153412701, 40596.0, 3457.550518432613, 3519.4544845548726, 3568.0544655666267, 3583.4445660922597, 3587.3722098632898, 31480.37039259202, 31998.360032799446, 32356.0, 32387.676123238703, 32508.0, 4402.207602631525, 4473.692034766743, 4514.774261286922, 4578.450585929677, 4601.746903920283, 313956.0, 321231.0, 325075.9999999976, 325146.0, 326605.0, 51515.0, 55940.720296398256, 58971.70514147402, 59038.70480647569, 59071.70464147652, 61107.38892611017, 61588.0, 61691.383086168564, 62070.0, 62225.0, 14701.117932924059, 15022.699546009018, 15117.395304187929, 15120.0, 15128.016678915836, 11739.295642261492, 11751.706207344885, 11826.52693892252, 11829.053675705924, 11833.704157396134, 17390.56523586895, 17541.736873946902, 17553.824461755474, 17559.73660395095, 17571.824281757017, 5854.795082172103, 5929.970350148222, 5936.22829032223, 6105.511559075263, 6113.816585502442, 1030.3766221436074, 1044.0916402729522, 1133.2747041893194, 1135.1883403366633, 1151.7638884028754, 32141.51787723186, 32718.67281327156, 32777.83611081929, 33013.0, 33362.332753344795, 1047.7747284333752, 1095.8411030400548, 1117.9161562882816, 1153.1254983256251, 1186.9881301186876, 16369.83630163707, 16610.0, 16665.3333866644, 16687.91656041736, 16723.91638041802, 254641.0, 255760.0, 255861.0, 257383.0, 535794.0, 57121.714391428606, 57162.714186429635, 57310.42689573134, 57441.13838292429, 57533.71233143807, 1333.6732500537237, 1362.9318534073257, 1363.6863521389937, 1368.2474638948538, 1371.7119404925077, 29944.401111977204, 32533.511997320533, 33004.66995330016, 33386.66613333835, 35917.0, 54796.72601636966, 55571.444285558246, 55796.72101639628, 55899.720501397234, 56023.439765603456, 486627.0, 498534.0, 498904.0, 503426.0, 503685.926281465, 27466.862665687342, 27710.584341234495, 27962.580561292005, 28010.43979120447, 28064.719352807027, 47585.0, 48493.51506485031, 48540.0, 48950.26574601312, 49109.50890491049, 41133.382999255635, 41425.0, 41679.374809377274, 42063.579364205965, 42091.0, 40514.0, 41062.0, 41297.79351103225, 41378.1724365505, 41442.58557414387, 40476.59523404728, 40983.59016409917, 42006.789966049975, 42160.36759448549, 44398.112037758416, 34948.30103397969, 37274.25451491009, 37757.0, 37782.0, 38424.42363364496, 27746.861265693544, 27959.0, 28650.14049578516, 28653.856730717045, 28897.42205155927], "value": 19701.83368176334, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.20236313194823, 394.22141270989783, 396.5320921312851, 397.3503322068419, 419.0675746464118, 17936.19287132079, 19082.61834763304, 19725.50686232845, 19824.30614928476, 19843.503912402197, 18378.0, 18437.17032733527, 18825.341113061044, 19128.617427651472, 19430.0, 5455.863603409926, 5542.140968149944, 5720.2849643794525, 5728.541716662678, 5729.713514324267, 5593.300837395325, 5599.63602365847, 5663.7168141592965, 5669.6314739541795, 5699.971500142494, 5398.352197736277, 5605.663660180402, 5626.859328516779, 5632.549396048306, 5636.04187288162, 5572.498475137217, 5594.328680558318, 5604.663720176803, 5612.719364031782, 5640.943590564103, 4809.927851082237, 4980.252962055684, 4984.102861484931, 4984.501549845023, 5019.0, 22215.0, 22451.21420750266, 22819.42951426211, 23228.53542929149, 23312.533749325088, 2308.2959697292317, 2411.794997425221, 2486.5275597636573, 2508.6487891694997, 2516.5344411283972, 9443.622255109787, 14385.0, 14605.196714180822, 14634.487792927197, 14873.776893346609, 1913.473794706453, 1945.7081437784445, 1963.783983761785, 2015.6841994918423, 2032.0, 83367.0, 83573.58213208956, 83883.5805820973, 84090.0, 85454.0, 10213.846792298122, 10464.267501274837, 10765.693722511058, 11117.27737697047, 11185.105191584817, 15284.847151528342, 15321.923390382977, 15326.0, 15326.693466130615, 15340.156291403857, 7476.214997425192, 8075.878861817077, 8108.797280068045, 8246.546439945741, 8292.792680183044, 7627.771166865003, 7695.884561731578, 7700.152983171846, 7802.843943121219, 7858.724944626911, 4564.406627138451, 4655.068986202706, 4673.439187297546, 4706.0823139487475, 4731.29030645406, 1791.5968906996081, 1797.4248240563156, 1816.5731053202487, 1824.3705921457342, 1828.7165489349172, 902.4134312696799, 913.365211178238, 971.2666936462925, 974.3033730882453, 981.1954197558076, 19026.238950441828, 19452.8054719451, 19656.3120290792, 19690.803091969185, 20182.89908550477, 938.8028514012134, 951.167728237792, 952.7951490429591, 963.1572374172599, 1007.8992100789862, 10761.843374156098, 11003.834942475869, 11010.348447732744, 11066.170037247239, 11110.167247142948, 444362.0, 448750.0, 479952.0, 488269.0, 495227.0, 85477.0, 86879.56560217158, 89636.0, 94055.0, 97486.51256743672, 1048.7378155461136, 1051.71603667011, 1069.2782371898936, 1075.295681328731, 1077.0199118801852, 17684.46946591604, 18025.09874506243, 18370.173342199367, 18374.816251837845, 18538.443846684622, 43745.12509749851, 43847.56152438562, 44078.55921440745, 44129.77935110432, 44429.00138987507, 57707.71146144242, 58372.70813645905, 61477.0, 62255.0, 63920.680396597716, 7908.4464087513325, 8621.852349529634, 8664.48013119215, 8867.467951922905, 8884.600192991202, 8829.870052098908, 13315.267660278778, 13548.72902541924, 13560.0, 13726.313684315945, 11114.333140011626, 11640.592579260025, 12122.212056216318, 12203.511859525875, 12316.384180790745, 11738.471768770254, 11779.0, 11928.463219155332, 11931.701707457383, 12071.939640301742, 11955.103367247317, 12203.267803931793, 12228.388580570758, 12399.938000309941, 12749.362531873554, 8713.477191368285, 8737.868931966153, 8815.118488151134, 8906.510141942255, 8962.283017358464, 9097.59060842277, 11922.76154476923, 12292.938535307267, 12335.75328493443, 12347.13570050088], "value": 9271.697698702217, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.8983671143017, 1496.8877334199935, 1635.2641311409861, 1758.5955230297038, 1790.695581751103, 7844.529328240295, 8512.276456501197, 8520.0, 9087.409318394308, 9138.817223655531, 8576.228139467446, 8932.062133475973, 8990.775230619238, 8991.460512369262, 9003.639854405832, 2303.896324665394, 2356.2931120663816, 2363.5745565798147, 2364.869932153731, 2366.3019409274307, 2299.8160147188187, 2327.429779703975, 2354.1878052072093, 2361.8700971446565, 2366.124533922451, 2326.639370897505, 2338.5206032763244, 2343.3672908314743, 2363.1492662641435, 2363.161077817367, 2311.711036120485, 2322.6167682332393, 2328.4062564046058, 2355.4111472131967, 2355.964660530085, 2341.8477798943013, 2355.364051706047, 2367.9644805327935, 2372.9406764830846, 2376.6910301660846, 9072.455652660861, 9123.817523649555, 9131.908680913173, 9137.954310228406, 9146.77133071672, 5083.288339632454, 5236.7119808410525, 5279.5512381447625, 5280.947190528084, 5310.0, 3112.673169317212, 3305.6529064448127, 3350.3634309481363, 3351.8156501392414, 3373.96626033739, 1054.1040115901421, 1059.348500672089, 1082.8538147350087, 1135.5911871726173, 1135.982960255597, 12432.937835310766, 12485.438155283035, 12551.686207844785, 12608.495660173581, 12616.81074783879, 4502.459704835441, 4540.455145382575, 4648.48866624671, 4681.0, 4709.646776491777, 10577.36535807854, 10582.894171058344, 10597.20520960931, 10604.734881627866, 10611.84082238767, 5201.765920533585, 5256.185291279858, 5263.6578622389425, 5266.4470230626, 5269.341332333458, 4392.0, 4745.454272758608, 4851.733154676526, 4852.539008794159, 4868.9756551217015, 4060.403503208044, 4078.5921407858978, 4119.567445418248, 4158.750474971512, 4159.91680166395, 1298.8701129887124, 1303.9674008149873, 1321.8744219299151, 1327.7344531093627, 1330.647378444713, 953.5661274120258, 962.3023308101571, 967.8161149381594, 981.1120935553336, 988.3526290279879, 6058.151858739692, 6107.603005804608, 6110.847228819315, 6213.565050446426, 6286.182796236551, 452.0506935435559, 452.35991072632515, 455.79944824277214, 456.2289730355688, 459.3913065188638, 4108.301588729923, 4372.256716358227, 4386.4736231652405, 4391.275439552438, 4443.377927090145, 10601.41692206936, 10698.465076746132, 10909.509072091774, 10929.781404371868, 10942.890571094187, 10810.0, 11182.0, 11206.495707693173, 11227.999999999836, 11237.83143252852, 8101.270885620204, 8164.387670924704, 8214.83570328602, 8247.711330103417, 8260.586970651564, 6195.907061394173, 6471.514636402383, 6487.253965793994, 6578.76974305897, 6579.342065793382, 10138.797224055625, 10313.381197128196, 10358.533865975898, 10443.68668939933, 10455.84316235242, 10473.581056757948, 10485.475726213812, 10491.0, 10497.10774584169, 10506.0, 7797.181295964069, 7898.328642065259, 7963.761087167394, 8171.591420429074, 8179.877301840358, 9113.134252246025, 9116.0, 9119.589618467053, 9120.543972801466, 9145.86281205769, 7723.684014888304, 8022.438429309892, 8068.87896681562, 8141.389395795458, 8149.755507334789, 7579.469437139347, 7723.189065148079, 7776.300132988058, 7880.645370958206, 7922.0889597694695, 7222.313880181375, 7338.302861228175, 7443.404527637667, 7449.180590135189, 7464.402847772057, 5238.319018527567, 5357.142857142839, 5425.891482170414, 5460.563154947516, 5515.4760297771645, 5258.710770907637, 5369.1140961740875, 5426.565874729933, 5454.400015998158, 5459.454054594509], "value": 4644.026282727928, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.6537470281502, 332.7389194951135, 336.14451221640905, 338.98474568644406, 339.30611898667223, 5539.224508568798, 5596.300462442195, 5607.439256074391, 5616.9157462637995, 5678.744456499458, 5503.367112782032, 5522.502974732273, 5534.446555344463, 5562.527185189264, 5569.192467092269, 4199.3281075028, 4304.2137512932095, 4320.956790432103, 4324.827006919735, 4333.891652708692, 4274.957250427487, 4287.735118140156, 4296.699231053827, 4313.482382114151, 4333.826646934134, 4239.194553034913, 4268.634037108111, 4286.549912259215, 4288.742675439484, 4307.0739790944945, 4069.837206511736, 4165.604267594574, 4171.666266698672, 4190.643795277405, 4196.433481479991, 3684.44733290006, 3688.391415416453, 3713.16453797896, 3726.795026273554, 3745.513083299181, 5393.83818485446, 5401.729913504349, 5413.0, 5477.0, 5483.341998960111, 4848.903021939542, 4877.926831097537, 4929.137400954819, 4936.925946110811, 4938.950610493885, 5828.388019257959, 5837.795677151322, 5856.824295271149, 5861.384554621746, 5864.0, 4135.627793498599, 4145.191687620915, 4152.6055024772595, 4157.854475093357, 4172.248995180867, 5901.527877769769, 6060.787872424444, 6075.453209211192, 6078.240219972504, 6106.236720409949, 4143.4199212110325, 4172.685604034719, 4205.074883525619, 4215.810288536962, 4216.177845320194, 4216.683748718859, 4240.745555266632, 4242.193983143193, 4258.808353624095, 4292.720973136736, 4627.0, 4879.487653796371, 4898.79979405048, 4914.975425122851, 4922.72924989129, 5854.151148083504, 5862.560307976833, 5865.0, 5912.17229587858, 5922.200502932135, 1460.4450308882556, 1476.5717941796759, 1500.6023403798001, 1501.144347721788, 1506.5254444849836, 933.5285680731323, 950.8573713942964, 954.9352471993677, 956.7751578379075, 957.8362289817766, 5102.4897510248675, 5118.59051275897, 5210.769323019676, 5210.843674689765, 5238.109521381298, 5459.672419654833, 5464.972675136599, 5477.589180811455, 5485.588580856452, 5535.533083732734, 4778.259369797687, 5209.166533354645, 5210.098314115321, 5236.423993360727, 5247.606429517801, 2878.913632591026, 2928.169893816365, 2931.6628587712257, 2941.8823247070304, 2957.8521073946217, 5250.3962044364625, 5584.106542953108, 5598.356189038232, 5620.213170156181, 5721.77112915487, 5360.437154098763, 5417.458254174551, 5435.402105768362, 5440.863978400572, 5452.727363631802, 5510.9448905511235, 5536.640118392372, 5555.972220138873, 5595.832125036255, 5597.5295316903785, 5222.686638801684, 5286.788528458818, 5315.6279060465395, 5342.9465705342445, 5365.758540865749, 5321.467853214803, 5339.4660533947845, 5365.624406291522, 5405.594580406407, 5437.341610808741, 5467.753951072132, 5479.876470411987, 5521.5306698931045, 5545.532238154144, 5665.830025099253, 3699.426588878728, 3910.1984093260244, 3910.8240129193687, 3919.3729003359326, 3934.370500719871, 5419.403865574863, 5480.712032672239, 5484.217629270583, 5527.226188333717, 5547.916781248366, 5229.326615483009, 5253.474652534716, 5280.0, 5281.260623512634, 5299.641659461503, 5054.898902021865, 5199.506046925536, 5219.739013049409, 5237.790488380422, 5301.522862942354, 5150.587952963829, 5151.2273159026445, 5604.971975140098, 5649.915251271316, 5824.359320474659, 5885.0, 5886.764529418947, 5894.469497745224, 5902.38025007368, 5937.762489500372, 5582.7766889323975, 5751.827445176651, 5782.277215348082, 5800.477956983892, 5809.983252930805], "value": 4255.294854280714, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.6987735028636, 744.5123444144081, 785.3089281432339, 804.919508049195, 852.9999999999998, 12580.559680411188, 13831.170129792206, 13832.723345533082, 13997.720045599082, 14107.78838317426, 13386.397612107454, 13865.168089914612, 13873.16760994341, 13878.653033674138, 13884.861151388508, 809.7489778168757, 818.7953011747063, 821.2813787935556, 826.4297634632114, 830.3232865214861, 807.2049031703767, 812.7114874219656, 813.4915677701437, 814.450246083894, 815.0748899998486, 865.4980111535294, 869.6434461870634, 871.0, 871.5337294547419, 871.7341210930664, 822.2558584481054, 857.8798968144465, 859.7463748194298, 859.7807559072459, 862.6894318045498, 608.2002167150183, 608.7230310208845, 609.9512039036911, 612.6569121292091, 614.7325913227716, 14088.577342679737, 14170.433182672681, 14172.0, 14177.291135443187, 14199.71600567983, 14066.367013484316, 14518.854811451962, 14585.562433126917, 14623.780643290253, 14676.0, 12271.325077120755, 12298.20061695987, 12299.569515066929, 12306.138570300083, 12368.505259789597, 3869.419587061935, 3875.476810630557, 3900.6294402031763, 3925.450436938831, 4017.0961533654986, 60359.0, 66201.33798662, 66580.99999999952, 66625.33374666289, 66711.33288667099, 10748.838767418494, 10757.246992710592, 10966.177536684781, 11099.83350249747, 11424.257423267461, 20623.896880515502, 20654.483637909172, 20808.167673293203, 20894.686579701316, 21087.8945605271, 8351.916480835114, 8375.748727538183, 8435.451695639764, 8462.534560599226, 8475.364347673949, 4519.88700282489, 4538.387317712067, 4538.841140560065, 4603.976980115078, 4638.652101092432, 3319.2697606526526, 3416.1630400552026, 3422.5208470814105, 3435.1927297085167, 3451.0682115828586, 592.7362323765883, 600.072887388985, 607.9088136779518, 613.6072913335469, 614.6373639552685, 651.6513665189101, 687.3298533929379, 737.6053811210976, 777.5723352156291, 779.8557266905527, 9262.305327100494, 9413.529323533796, 9687.225021998363, 9744.707658770249, 9755.0, 593.9227900372924, 672.351181110231, 715.059696499102, 716.9426445884317, 722.1262272650152, 4868.172410690191, 4939.2838038484215, 4980.601551875914, 4988.800447982113, 4990.525900039491, 72597.0, 75136.0, 75161.0, 75375.0, 75787.0, 66988.0, 67463.0, 67563.0, 67892.00808048646, 68268.6586567064, 62385.0, 62966.37033629697, 63214.0, 63342.36657633365, 63941.68029159917, 9375.85936210971, 9566.378185417925, 9616.75958101025, 9618.711438656852, 9716.0, 36559.26881462303, 37724.81137594295, 38462.807685961394, 40018.0, 40472.79763601163, 49199.26201106915, 49404.75297623633, 49528.50471495239, 50238.74880625573, 55867.0, 12503.37483125822, 13098.803517947048, 13100.60698179056, 13375.123361584629, 13965.790513142514, 13994.790078149039, 14097.647558810702, 14272.928635356757, 14521.564353069425, 14885.404583816526, 11235.606753763906, 11865.996417175475, 11918.52325906954, 12328.583818609837, 12625.936870315589, 13702.0, 13832.930835345758, 13970.860291396955, 14256.572302830931, 14691.191984440959, 13113.213207207598, 13184.27486488214, 13193.0, 13242.57980352229, 13708.45166193381, 10053.447060411456, 10064.748381290527, 10548.208884333551, 10583.4708264588, 10918.0, 11146.88853111491, 11209.327440353605, 11294.265872718248, 11311.434428278717, 11624.186306958432], "value": 7069.265218772212, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 112.0, 110.0, 112.0, 111.0, 110.0, 110.0, 110.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 126.0, 149.0, 176.0, 126.0, 158.0, 159.0, 128.0, 196.0, 136.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 164.0, 161.0, 163.0, 163.0, 178.0, 162.0, 161.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 148.0, 77.0, 75.0, 75.0, 73.0, 170.0, 77.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 151.0, 83.0, 82.0, 83.0, 138.0, 83.0, 82.0, 132.0], "value": 100.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 120.0, 118.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 53.0, 55.0, 53.0, 54.0, 54.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 61.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 89.0, 81.0, 80.0, 82.0, 80.0, 80.0, 112.0, 81.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 133.0, 126.0, 120.0, 130.0, 138.0, 127.0, 133.0, 134.0, 123.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 158.0, 163.0, 169.0, 163.0, 159.0, 157.0, 164.0, 159.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 64.0, 64.0, 68.0, 64.0, 61.0, 63.0, 61.0, 61.0], "value": 63.2, "unit": "score"}]}]} 22:26:04 INFO - Return code: 0 22:26:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:26:04 ERROR - # TBPL SUCCESS # 22:26:04 INFO - Running post-action listener: _resource_record_post_action 22:26:04 INFO - ##### 22:26:04 INFO - ##### Finished run-tests step (success) 22:26:04 INFO - ##### 22:26:04 INFO - Running post-run listener: _resource_record_post_run 22:26:04 INFO - Total resource usage - Wall time: 1191s; CPU: 14.0%; Read bytes: 12288; Write bytes: 540426240; Read time: 392; Write time: 451184 22:26:04 INFO - TinderboxPrint: CPU usage
13.6% 22:26:04 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 392 22:26:04 INFO - TinderboxPrint: I/O write bytes / time
540,426,240 / 451,184 22:26:04 INFO - TinderboxPrint: CPU idle
8,209.5 (86.4%) 22:26:04 INFO - TinderboxPrint: CPU user
1,233.5 (13.0%) 22:26:04 INFO - TinderboxPrint: Swap in / out
0 / 0 22:26:04 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 247586816; Read time: 140; Write time: 278868 22:26:04 INFO - run-tests - Wall time: 1174s; CPU: 14.0%; Read bytes: 8192; Write bytes: 292839424; Read time: 252; Write time: 172316 22:26:05 INFO - Running post-run listener: _upload_blobber_files 22:26:05 INFO - Blob upload gear active. 22:26:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:26:05 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 22:26:05 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:26:05 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:26:05 INFO - (blobuploader) - INFO - Open directory for files ... 22:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:06 INFO - (blobuploader) - INFO - Done attempting. 22:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:26:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:07 INFO - (blobuploader) - INFO - Done attempting. 22:26:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:26:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:26:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:11 INFO - (blobuploader) - INFO - Done attempting. 22:26:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:26:11 INFO - Return code: 0 22:26:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:26:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:26:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef68811c93ffe55c57e477feec5e8a7716a292c1e4902766447b2e2ce35e7b2b83499e225fc0ae7be937a52e3a51c1508032b7e169b68a58e8e876a5d92da39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3451b89ff2abac37fb1f24c7d2cd4201958cdd8e808be239d2228a79d9703b8df4121bdab3218bedf8daec351f39376a97f4e1e1aa3e06638c756a755add3de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77da3fa029fd620d30680e5b2e161bfdcedbf188ea02089066e84f8c96b97c45239c8493b5089c8ae5031472b1a1d3a15226e9da74b352377554bf91227ed8fb"} 22:26:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:26:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:26:11 INFO - Contents: 22:26:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef68811c93ffe55c57e477feec5e8a7716a292c1e4902766447b2e2ce35e7b2b83499e225fc0ae7be937a52e3a51c1508032b7e169b68a58e8e876a5d92da39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3451b89ff2abac37fb1f24c7d2cd4201958cdd8e808be239d2228a79d9703b8df4121bdab3218bedf8daec351f39376a97f4e1e1aa3e06638c756a755add3de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77da3fa029fd620d30680e5b2e161bfdcedbf188ea02089066e84f8c96b97c45239c8493b5089c8ae5031472b1a1d3a15226e9da74b352377554bf91227ed8fb"} 22:26:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:26:11 INFO - Copying logs to upload dir... 22:26:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:26:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.197103 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-05-31 22:26:11.969918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 22:26:11.973806) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef68811c93ffe55c57e477feec5e8a7716a292c1e4902766447b2e2ce35e7b2b83499e225fc0ae7be937a52e3a51c1508032b7e169b68a58e8e876a5d92da39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3451b89ff2abac37fb1f24c7d2cd4201958cdd8e808be239d2228a79d9703b8df4121bdab3218bedf8daec351f39376a97f4e1e1aa3e06638c756a755add3de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77da3fa029fd620d30680e5b2e161bfdcedbf188ea02089066e84f8c96b97c45239c8493b5089c8ae5031472b1a1d3a15226e9da74b352377554bf91227ed8fb"} build_url:https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/IcUiywEKTSeGJVnLLB9AYw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eef68811c93ffe55c57e477feec5e8a7716a292c1e4902766447b2e2ce35e7b2b83499e225fc0ae7be937a52e3a51c1508032b7e169b68a58e8e876a5d92da39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3451b89ff2abac37fb1f24c7d2cd4201958cdd8e808be239d2228a79d9703b8df4121bdab3218bedf8daec351f39376a97f4e1e1aa3e06638c756a755add3de", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77da3fa029fd620d30680e5b2e161bfdcedbf188ea02089066e84f8c96b97c45239c8493b5089c8ae5031472b1a1d3a15226e9da74b352377554bf91227ed8fb"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 22:26:11.999839) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:26:12.000163) ========= 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=4ac8d263c7293b36a1be1aed000001be-1464754196.997895-885909328 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 22:26:12.085410) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 22:26:12.085737) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 22:26:12.086017) ========= ========= Total master_lag: 0.18 =========