builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-054 starttime: 1463636613.14 results: success (0) buildid: 20160518214837 builduid: 094b2a59163542b0baa199377e29d518 revision: 0ba8107314d6e579f881399cc2ec0a6478080a90 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.141334) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.141933) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.142343) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.171131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.171758) ========= 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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-18 22:43:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 222M=0s 2016-05-18 22:43:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145728 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.338630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.339144) ========= 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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018167 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.390032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:33.390405) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0ba8107314d6e579f881399cc2ec0a6478080a90 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0ba8107314d6e579f881399cc2ec0a6478080a90 --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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-18 22:43:33,738 truncating revision to first 12 chars 2016-05-18 22:43:33,739 Setting DEBUG logging. 2016-05-18 22:43:33,739 attempt 1/10 2016-05-18 22:43:33,739 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0ba8107314d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 22:43:34,226 unpacking tar archive at: fx-team-0ba8107314d6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.760954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:34.167599) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:34.168151) ========= script_repo_revision: 0ba8107314d6e579f881399cc2ec0a6478080a90 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:34.168842) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:34.169308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 22:43:34.179734) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 22 secs) (at 2016-05-18 22:43:34.180123) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:43:34 INFO - MultiFileLogger online at 20160518 22:43:34 in /builds/slave/test 22:43:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 22:43:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:43:34 INFO - {'append_to_log': False, 22:43:34 INFO - 'base_work_dir': '/builds/slave/test', 22:43:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:43:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:43:34 INFO - 'branch': 'Fx-Team-Non-PGO', 22:43:34 INFO - 'buildbot_json_path': 'buildprops.json', 22:43:34 INFO - 'config_files': ('talos/linux_config.py',), 22:43:34 INFO - 'default_actions': ('clobber', 22:43:34 INFO - 'read-buildbot-config', 22:43:34 INFO - 'download-and-extract', 22:43:34 INFO - 'populate-webroot', 22:43:34 INFO - 'create-virtualenv', 22:43:34 INFO - 'install', 22:43:34 INFO - 'run-tests'), 22:43:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:43:34 INFO - 'download_minidump_stackwalk': True, 22:43:34 INFO - 'download_symbols': 'ondemand', 22:43:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:43:34 INFO - 'tooltool.py': '/tools/tooltool.py', 22:43:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:43:34 INFO - '/tools/misc-python/virtualenv.py')}, 22:43:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:43:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:43:34 INFO - 'installer_path': 'installer.exe', 22:43:34 INFO - 'log_level': 'info', 22:43:34 INFO - 'log_name': 'talos', 22:43:34 INFO - 'log_to_console': True, 22:43:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:43:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:43:34 INFO - 'opt_config_files': (), 22:43:34 INFO - 'pip_index': False, 22:43:34 INFO - 'sps_profile': False, 22:43:34 INFO - 'sps_profile_interval': 0, 22:43:34 INFO - 'suite': 'dromaeojs', 22:43:34 INFO - 'system_bits': '32', 22:43:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:43:34 INFO - 'title': 'talos-linux64-ix-054', 22:43:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:43:34 INFO - 'use_talos_json': True, 22:43:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:43:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:43:34 INFO - 'work_dir': 'build'} 22:43:34 INFO - ##### 22:43:34 INFO - ##### Running clobber step. 22:43:34 INFO - ##### 22:43:34 INFO - Running pre-action listener: _resource_record_pre_action 22:43:34 INFO - Running main action method: clobber 22:43:34 INFO - rmtree: /builds/slave/test/build 22:43:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:43:35 INFO - Running post-action listener: _resource_record_post_action 22:43:35 INFO - ##### 22:43:35 INFO - ##### Finished clobber step (success) 22:43:35 INFO - ##### 22:43:35 INFO - ##### 22:43:35 INFO - ##### Running read-buildbot-config step. 22:43:35 INFO - ##### 22:43:35 INFO - Running pre-action listener: _resource_record_pre_action 22:43:35 INFO - Running main action method: read_buildbot_config 22:43:35 INFO - Using buildbot properties: 22:43:35 INFO - { 22:43:35 INFO - "project": "", 22:43:35 INFO - "product": "firefox", 22:43:35 INFO - "script_repo_revision": "production", 22:43:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:43:35 INFO - "repository": "", 22:43:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:43:35 INFO - "buildid": "20160518214837", 22:43:35 INFO - "pgo_build": "False", 22:43:35 INFO - "basedir": "/builds/slave/test", 22:43:35 INFO - "buildnumber": 1698, 22:43:35 INFO - "slavename": "talos-linux64-ix-054", 22:43:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:43:35 INFO - "platform": "ubuntu64_hw", 22:43:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:43:35 INFO - "branch": "fx-team", 22:43:35 INFO - "repo_path": "integration/fx-team", 22:43:35 INFO - "revision": "0ba8107314d6e579f881399cc2ec0a6478080a90", 22:43:35 INFO - "stage_platform": "linux64", 22:43:35 INFO - "builduid": "094b2a59163542b0baa199377e29d518", 22:43:35 INFO - "slavebuilddir": "test" 22:43:35 INFO - } 22:43:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:43:35 INFO - Running post-action listener: _resource_record_post_action 22:43:35 INFO - ##### 22:43:35 INFO - ##### Finished read-buildbot-config step (success) 22:43:35 INFO - ##### 22:43:35 INFO - ##### 22:43:35 INFO - ##### Running download-and-extract step. 22:43:35 INFO - ##### 22:43:35 INFO - Running pre-action listener: _resource_record_pre_action 22:43:35 INFO - Running main action method: download_and_extract 22:43:35 INFO - mkdir: /builds/slave/test/build/tests 22:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:35 INFO - https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:43:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:43:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:43:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:37 INFO - Downloaded 1361 bytes. 22:43:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:43:37 INFO - Using the following test package requirements: 22:43:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:43:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:43:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:43:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'jsshell-linux-x86_64.zip'], 22:43:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:43:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:43:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:43:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:43:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:43:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:43:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:37 INFO - https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:43:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:43:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:43:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:45 INFO - Downloaded 22812201 bytes. 22:43:45 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:43:45 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:43:46 INFO - Return code: 0 22:43:46 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:43: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:43:46 INFO - https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:43:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:43:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:43:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:47 INFO - Downloaded 22812201 bytes. 22:43:47 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:43:47 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:43:48 INFO - Return code: 0 22:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:48 INFO - https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:43:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:43:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:43:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:50 INFO - Downloaded 10840194 bytes. 22:43:50 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:43:50 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:43:50 INFO - Return code: 0 22:43:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:50 INFO - https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:43:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:43:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:43:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R3DSw0anTuy6i25uAJ0t_A/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:43:52 INFO - Downloaded 54799421 bytes. 22:43:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:43:52 INFO - mkdir: /builds/slave/test/properties 22:43:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:43:52 INFO - Writing to file /builds/slave/test/properties/build_url 22:43:52 INFO - Contents: 22:43:52 INFO - build_url:https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:43:53 INFO - Running post-action listener: _resource_record_post_action 22:43:53 INFO - Running post-action listener: set_extra_try_arguments 22:43:53 INFO - ##### 22:43:53 INFO - ##### Finished download-and-extract step (success) 22:43:53 INFO - ##### 22:43:53 INFO - ##### 22:43:53 INFO - ##### Running populate-webroot step. 22:43:53 INFO - ##### 22:43:53 INFO - Running pre-action listener: _resource_record_pre_action 22:43:53 INFO - Running main action method: populate_webroot 22:43:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:43:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:43:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:43:53 INFO - '--tppagecycles', 22:43:53 INFO - '7'], 22:43:53 INFO - 'tests': ['tsvgm']}}, 22:43:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:43:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:43:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:43:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:43:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['damp', 'tps']}, 22:43:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'tests': ['damp', 'tps']}, 22:43:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['dromaeo_dom']}, 22:43:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:43:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'other-e10s': {'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['a11yr', 22:43:53 INFO - 'ts_paint', 22:43:53 INFO - 'tpaint', 22:43:53 INFO - 'sessionrestore', 22:43:53 INFO - 'sessionrestore_no_auto_restore', 22:43:53 INFO - 'tabpaint']}, 22:43:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['tsvgx', 22:43:53 INFO - 'tsvgr_opacity', 22:43:53 INFO - 'tart', 22:43:53 INFO - 'tscrollx', 22:43:53 INFO - 'cart']}, 22:43:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:43:53 INFO - 'tsvgr_opacity', 22:43:53 INFO - 'tart', 22:43:53 INFO - 'tscrollx', 22:43:53 INFO - 'cart']}, 22:43:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'talos_options': ['--disable-e10s'], 22:43:53 INFO - 'tests': ['tp5o']}, 22:43:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'tests': ['tp5o']}, 22:43:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'talos_options': ['--disable-e10s', 22:43:53 INFO - '--xperf_path', 22:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:53 INFO - 'tests': ['tp5n']}, 22:43:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:53 INFO - 'talos_options': ['--xperf_path', 22:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:53 INFO - 'tests': ['tp5n']}}, 22:43:53 INFO - 'talos.zip': {'path': '', 22:43:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:43:53 INFO - Running post-action listener: _resource_record_post_action 22:43:53 INFO - ##### 22:43:53 INFO - ##### Finished populate-webroot step (success) 22:43:53 INFO - ##### 22:43:53 INFO - ##### 22:43:53 INFO - ##### Running create-virtualenv step. 22:43:53 INFO - ##### 22:43:53 INFO - Running pre-action listener: _resource_record_pre_action 22:43:53 INFO - Running main action method: create_virtualenv 22:43:53 INFO - Creating virtualenv /builds/slave/test/build/venv 22:43:53 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:43:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:43:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:43:53 INFO - Using real prefix '/usr' 22:43:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:43:53 INFO - Installing distribute.............................................................................................................................................................................................done. 22:43:55 INFO - Installing pip.................done. 22:43:55 INFO - Return code: 0 22:43:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:55 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:43:55 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:43:55 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:43:55 INFO - Using env: {'DISPLAY': ':0', 22:43:55 INFO - 'HOME': '/home/cltbld', 22:43:55 INFO - 'LANG': 'en_US.UTF-8', 22:43:55 INFO - 'LANGUAGE': 'en_US:en', 22:43:55 INFO - 'LOGNAME': 'cltbld', 22:43:55 INFO - 'MAIL': '/var/mail/cltbld', 22:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:55 INFO - 'MOZ_NO_REMOTE': '1', 22:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:55 INFO - 'NO_EM_RESTART': '1', 22:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:55 INFO - 'PWD': '/builds/slave/test', 22:43:55 INFO - 'SHELL': '/bin/bash', 22:43:55 INFO - 'SHLVL': '1', 22:43:55 INFO - 'TERM': 'linux', 22:43:55 INFO - 'TMOUT': '86400', 22:43:55 INFO - 'USER': 'cltbld', 22:43:55 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:55 INFO - '_': '/tools/buildbot/bin/python'} 22:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:55 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:43:55 INFO - Cleaning up... 22:43:55 INFO - Return code: 0 22:43:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:55 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:43:55 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:43:55 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:43:55 INFO - Using env: {'DISPLAY': ':0', 22:43:55 INFO - 'HOME': '/home/cltbld', 22:43:55 INFO - 'LANG': 'en_US.UTF-8', 22:43:55 INFO - 'LANGUAGE': 'en_US:en', 22:43:55 INFO - 'LOGNAME': 'cltbld', 22:43:55 INFO - 'MAIL': '/var/mail/cltbld', 22:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:55 INFO - 'MOZ_NO_REMOTE': '1', 22:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:55 INFO - 'NO_EM_RESTART': '1', 22:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:55 INFO - 'PWD': '/builds/slave/test', 22:43:55 INFO - 'SHELL': '/bin/bash', 22:43:55 INFO - 'SHLVL': '1', 22:43:55 INFO - 'TERM': 'linux', 22:43:55 INFO - 'TMOUT': '86400', 22:43:55 INFO - 'USER': 'cltbld', 22:43:55 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:55 INFO - '_': '/tools/buildbot/bin/python'} 22:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:56 INFO - Downloading/unpacking psutil>=3.1.1 22:43:56 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:43:56 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:43:56 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:43:56 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:43:56 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:43:56 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:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:43:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:58 INFO - Installing collected packages: psutil 22:43:58 INFO - Running setup.py install for psutil 22:43:58 INFO - building 'psutil._psutil_linux' extension 22:43:58 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:43:58 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:43:58 INFO - building 'psutil._psutil_posix' extension 22:43:58 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:43:58 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:43:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:58 INFO - Successfully installed psutil 22:43:58 INFO - Cleaning up... 22:43:58 INFO - Return code: 0 22:43:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:43:58 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:43:58 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:43:58 INFO - Using env: {'DISPLAY': ':0', 22:43:58 INFO - 'HOME': '/home/cltbld', 22:43:58 INFO - 'LANG': 'en_US.UTF-8', 22:43:58 INFO - 'LANGUAGE': 'en_US:en', 22:43:58 INFO - 'LOGNAME': 'cltbld', 22:43:58 INFO - 'MAIL': '/var/mail/cltbld', 22:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:58 INFO - 'MOZ_NO_REMOTE': '1', 22:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:58 INFO - 'NO_EM_RESTART': '1', 22:43:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:43:58 INFO - 'PWD': '/builds/slave/test', 22:43:58 INFO - 'SHELL': '/bin/bash', 22:43:58 INFO - 'SHLVL': '1', 22:43:58 INFO - 'TERM': 'linux', 22:43:58 INFO - 'TMOUT': '86400', 22:43:58 INFO - 'USER': 'cltbld', 22:43:58 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:58 INFO - '_': '/tools/buildbot/bin/python'} 22:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:43: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:43: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:43: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:43: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:43: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:43: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:44:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:44:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:44:00 INFO - Installing collected packages: mozsystemmonitor 22:44:00 INFO - Running setup.py install for mozsystemmonitor 22:44:01 INFO - Successfully installed mozsystemmonitor 22:44:01 INFO - Cleaning up... 22:44:01 INFO - Return code: 0 22:44:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:44:01 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:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:01 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:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:01 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:01 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:44:01 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:44:01 INFO - Using env: {'DISPLAY': ':0', 22:44:01 INFO - 'HOME': '/home/cltbld', 22:44:01 INFO - 'LANG': 'en_US.UTF-8', 22:44:01 INFO - 'LANGUAGE': 'en_US:en', 22:44:01 INFO - 'LOGNAME': 'cltbld', 22:44:01 INFO - 'MAIL': '/var/mail/cltbld', 22:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:01 INFO - 'MOZ_NO_REMOTE': '1', 22:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:01 INFO - 'NO_EM_RESTART': '1', 22:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:01 INFO - 'PWD': '/builds/slave/test', 22:44:01 INFO - 'SHELL': '/bin/bash', 22:44:01 INFO - 'SHLVL': '1', 22:44:01 INFO - 'TERM': 'linux', 22:44:01 INFO - 'TMOUT': '86400', 22:44:01 INFO - 'USER': 'cltbld', 22:44:01 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:01 INFO - '_': '/tools/buildbot/bin/python'} 22:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:01 INFO - Downloading/unpacking blobuploader==1.2.4 22:44:01 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:44:01 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:44:01 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:44:01 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:44:01 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:44:01 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:44:03 INFO - Downloading blobuploader-1.2.4.tar.gz 22:44:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:44:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:44:03 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:44:03 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:44:03 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:44:03 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:44:03 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:44:03 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:44:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:44:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:44:03 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:44:03 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:44:03 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:44:03 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:44:03 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:44:03 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:44:03 INFO - Downloading docopt-0.6.1.tar.gz 22:44:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:44:03 INFO - Installing collected packages: blobuploader, requests, docopt 22:44:03 INFO - Running setup.py install for blobuploader 22:44:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:44:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:44:04 INFO - Running setup.py install for requests 22:44:04 INFO - Running setup.py install for docopt 22:44:04 INFO - Successfully installed blobuploader requests docopt 22:44:04 INFO - Cleaning up... 22:44:04 INFO - Return code: 0 22:44:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:04 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:04 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:44:04 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:44:04 INFO - Using env: {'DISPLAY': ':0', 22:44:04 INFO - 'HOME': '/home/cltbld', 22:44:04 INFO - 'LANG': 'en_US.UTF-8', 22:44:04 INFO - 'LANGUAGE': 'en_US:en', 22:44:04 INFO - 'LOGNAME': 'cltbld', 22:44:04 INFO - 'MAIL': '/var/mail/cltbld', 22:44:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:04 INFO - 'MOZ_NO_REMOTE': '1', 22:44:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:04 INFO - 'NO_EM_RESTART': '1', 22:44:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:04 INFO - 'PWD': '/builds/slave/test', 22:44:04 INFO - 'SHELL': '/bin/bash', 22:44:04 INFO - 'SHLVL': '1', 22:44:04 INFO - 'TERM': 'linux', 22:44:04 INFO - 'TMOUT': '86400', 22:44:04 INFO - 'USER': 'cltbld', 22:44:04 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:04 INFO - '_': '/tools/buildbot/bin/python'} 22:44:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:04 INFO - Running setup.py (path:/tmp/pip-UMcS2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:04 INFO - Running setup.py (path:/tmp/pip-CSELlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:04 INFO - Running setup.py (path:/tmp/pip-T3mYp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:05 INFO - Running setup.py (path:/tmp/pip-5IBi9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:05 INFO - Running setup.py (path:/tmp/pip-qsHd25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:05 INFO - Running setup.py (path:/tmp/pip-gSYQUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:05 INFO - Running setup.py (path:/tmp/pip-VEooDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:05 INFO - Running setup.py (path:/tmp/pip-mOzbHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:05 INFO - Running setup.py (path:/tmp/pip-RKxIvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:05 INFO - Running setup.py (path:/tmp/pip-yjyIcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:05 INFO - Running setup.py (path:/tmp/pip-_GBVrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:05 INFO - Running setup.py (path:/tmp/pip-b0JPmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:05 INFO - Running setup.py (path:/tmp/pip-501n6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:05 INFO - Running setup.py (path:/tmp/pip-8xrY1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:05 INFO - Running setup.py (path:/tmp/pip-puAQ2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:05 INFO - Running setup.py (path:/tmp/pip-lJflQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:05 INFO - Running setup.py (path:/tmp/pip-aTh8UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:44:05 INFO - Running setup.py install for manifestparser 22:44:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Running setup.py install for mozcrash 22:44:06 INFO - Running setup.py install for mozdebug 22:44:06 INFO - Running setup.py install for mozdevice 22:44:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Running setup.py install for mozfile 22:44:06 INFO - Running setup.py install for mozhttpd 22:44:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Running setup.py install for mozinfo 22:44:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Running setup.py install for mozInstall 22:44:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:44:06 INFO - Running setup.py install for mozleak 22:44:06 INFO - Running setup.py install for mozlog 22:44:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Running setup.py install for moznetwork 22:44:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Running setup.py install for mozprocess 22:44:07 INFO - Running setup.py install for mozprofile 22:44:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Running setup.py install for mozrunner 22:44:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Running setup.py install for mozscreenshot 22:44:07 INFO - Running setup.py install for moztest 22:44:07 INFO - Running setup.py install for mozversion 22:44:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:44:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:44:07 INFO - Cleaning up... 22:44:07 INFO - Return code: 0 22:44:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44: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:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:07 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:07 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:44:07 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:44:07 INFO - Using env: {'DISPLAY': ':0', 22:44:07 INFO - 'HOME': '/home/cltbld', 22:44:07 INFO - 'LANG': 'en_US.UTF-8', 22:44:07 INFO - 'LANGUAGE': 'en_US:en', 22:44:07 INFO - 'LOGNAME': 'cltbld', 22:44:07 INFO - 'MAIL': '/var/mail/cltbld', 22:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:07 INFO - 'MOZ_NO_REMOTE': '1', 22:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:07 INFO - 'NO_EM_RESTART': '1', 22:44:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:07 INFO - 'PWD': '/builds/slave/test', 22:44:07 INFO - 'SHELL': '/bin/bash', 22:44:07 INFO - 'SHLVL': '1', 22:44:07 INFO - 'TERM': 'linux', 22:44:07 INFO - 'TMOUT': '86400', 22:44:07 INFO - 'USER': 'cltbld', 22:44:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:07 INFO - '_': '/tools/buildbot/bin/python'} 22:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:44:08 INFO - Running setup.py (path:/tmp/pip-lfb9PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:44:08 INFO - Running setup.py (path:/tmp/pip-kFtx4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:44:08 INFO - Running setup.py (path:/tmp/pip-j2N3iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:44:08 INFO - Running setup.py (path:/tmp/pip-D59lXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:44:08 INFO - Running setup.py (path:/tmp/pip-pc0qnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:44:08 INFO - Running setup.py (path:/tmp/pip-2fZqhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:44:08 INFO - Running setup.py (path:/tmp/pip-6DahKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:44:08 INFO - Running setup.py (path:/tmp/pip-4sHQjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:44:08 INFO - Running setup.py (path:/tmp/pip-zoMjY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:44:08 INFO - Running setup.py (path:/tmp/pip-V0QcAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:44:08 INFO - Running setup.py (path:/tmp/pip-E4_WmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:44:08 INFO - Running setup.py (path:/tmp/pip-2EvLtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:44:08 INFO - Running setup.py (path:/tmp/pip-9Q27A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:44:08 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:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:44:08 INFO - Running setup.py (path:/tmp/pip-zbreMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:44:09 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:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:09 INFO - Running setup.py (path:/tmp/pip-QrLiOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:44:09 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:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:44:09 INFO - Running setup.py (path:/tmp/pip-mPwYKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:44:09 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:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:44:09 INFO - Running setup.py (path:/tmp/pip-7Qvo9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:44:09 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:44:09 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:44:09 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:44:09 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:44:09 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:44:09 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:44:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:44: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:44: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:44: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:44: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:44: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:44: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:44:11 INFO - Downloading blessings-1.6.tar.gz 22:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:44:11 INFO - Installing collected packages: blessings 22:44:11 INFO - Running setup.py install for blessings 22:44:11 INFO - Successfully installed blessings 22:44:11 INFO - Cleaning up... 22:44:11 INFO - Return code: 0 22:44:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:11 INFO - Reading from file tmpfile_stdout 22:44:11 INFO - Current package versions: 22:44:11 INFO - argparse == 1.2.1 22:44:11 INFO - blessings == 1.6 22:44:11 INFO - blobuploader == 1.2.4 22:44:11 INFO - docopt == 0.6.1 22:44:11 INFO - manifestparser == 1.1 22:44:11 INFO - mozInstall == 1.12 22:44:11 INFO - mozcrash == 0.17 22:44:11 INFO - mozdebug == 0.1 22:44:11 INFO - mozdevice == 0.48 22:44:11 INFO - mozfile == 1.2 22:44:11 INFO - mozhttpd == 0.7 22:44:11 INFO - mozinfo == 0.9 22:44:11 INFO - mozleak == 0.1 22:44:11 INFO - mozlog == 3.2 22:44:11 INFO - moznetwork == 0.27 22:44:11 INFO - mozprocess == 0.22 22:44:11 INFO - mozprofile == 0.28 22:44:11 INFO - mozrunner == 6.11 22:44:11 INFO - mozscreenshot == 0.1 22:44:11 INFO - mozsystemmonitor == 0.3 22:44:11 INFO - moztest == 0.7 22:44:11 INFO - mozversion == 1.4 22:44:11 INFO - psutil == 3.1.1 22:44:11 INFO - requests == 1.2.3 22:44:11 INFO - wsgiref == 0.1.2 22:44:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:11 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 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:11 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:44:11 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:44:11 INFO - Using env: {'DISPLAY': ':0', 22:44:11 INFO - 'HOME': '/home/cltbld', 22:44:11 INFO - 'LANG': 'en_US.UTF-8', 22:44:11 INFO - 'LANGUAGE': 'en_US:en', 22:44:11 INFO - 'LOGNAME': 'cltbld', 22:44:11 INFO - 'MAIL': '/var/mail/cltbld', 22:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:11 INFO - 'MOZ_NO_REMOTE': '1', 22:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:11 INFO - 'NO_EM_RESTART': '1', 22:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:11 INFO - 'PWD': '/builds/slave/test', 22:44:11 INFO - 'SHELL': '/bin/bash', 22:44:11 INFO - 'SHLVL': '1', 22:44:11 INFO - 'TERM': 'linux', 22:44:11 INFO - 'TMOUT': '86400', 22:44:11 INFO - 'USER': 'cltbld', 22:44:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:11 INFO - '_': '/tools/buildbot/bin/python'} 22:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 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:44:11 INFO - Cleaning up... 22:44:11 INFO - Return code: 0 22:44:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:44:11 INFO - Using env: {'DISPLAY': ':0', 22:44:11 INFO - 'HOME': '/home/cltbld', 22:44:11 INFO - 'LANG': 'en_US.UTF-8', 22:44:11 INFO - 'LANGUAGE': 'en_US:en', 22:44:11 INFO - 'LOGNAME': 'cltbld', 22:44:11 INFO - 'MAIL': '/var/mail/cltbld', 22:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:11 INFO - 'MOZ_NO_REMOTE': '1', 22:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:11 INFO - 'NO_EM_RESTART': '1', 22:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:11 INFO - 'PWD': '/builds/slave/test', 22:44:11 INFO - 'SHELL': '/bin/bash', 22:44:11 INFO - 'SHLVL': '1', 22:44:11 INFO - 'TERM': 'linux', 22:44:11 INFO - 'TMOUT': '86400', 22:44:11 INFO - 'USER': 'cltbld', 22:44:11 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:11 INFO - '_': '/tools/buildbot/bin/python'} 22:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:12 INFO - Downloading/unpacking jsonschema 22:44: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:44: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:44: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:44: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:44: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:44: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:44:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:44:13 INFO - Downloading/unpacking functools32 (from jsonschema) 22:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - Downloading functools32-3.2.3-2.tar.gz 22:44:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:44:14 INFO - warning: no files found matching '*.txt' 22:44:14 INFO - no previously-included directories found matching 'build' 22:44:14 INFO - no previously-included directories found matching 'dist' 22:44:14 INFO - no previously-included directories found matching '.git*' 22:44:14 INFO - Installing collected packages: jsonschema, functools32 22:44:14 INFO - Running setup.py install for functools32 22:44:14 INFO - warning: no files found matching '*.txt' 22:44:14 INFO - no previously-included directories found matching 'build' 22:44:14 INFO - no previously-included directories found matching 'dist' 22:44:14 INFO - no previously-included directories found matching '.git*' 22:44:14 INFO - Successfully installed jsonschema functools32 22:44:14 INFO - Cleaning up... 22:44:14 INFO - Return code: 0 22:44:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f250dcc9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dc770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, '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': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', '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:44:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:44:14 INFO - Using env: {'DISPLAY': ':0', 22:44:14 INFO - 'HOME': '/home/cltbld', 22:44:14 INFO - 'LANG': 'en_US.UTF-8', 22:44:14 INFO - 'LANGUAGE': 'en_US:en', 22:44:14 INFO - 'LOGNAME': 'cltbld', 22:44:14 INFO - 'MAIL': '/var/mail/cltbld', 22:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:14 INFO - 'MOZ_NO_REMOTE': '1', 22:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:14 INFO - 'NO_EM_RESTART': '1', 22:44:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:14 INFO - 'PWD': '/builds/slave/test', 22:44:14 INFO - 'SHELL': '/bin/bash', 22:44:14 INFO - 'SHLVL': '1', 22:44:14 INFO - 'TERM': 'linux', 22:44:14 INFO - 'TMOUT': '86400', 22:44:14 INFO - 'USER': 'cltbld', 22:44:14 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:14 INFO - '_': '/tools/buildbot/bin/python'} 22:44:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:14 INFO - Downloading/unpacking flake8 22:44:14 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:44:14 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:44:14 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:44:14 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:44:14 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:44:14 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:44:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:44:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:44:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:16 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:44:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:44:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:17 INFO - no previously-included directories found matching 'docs/_build' 22:44:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:44:17 INFO - Running setup.py install for pep8 22:44:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:17 INFO - no previously-included directories found matching 'docs/_build' 22:44:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:44:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:44:17 INFO - Cleaning up... 22:44:17 INFO - Return code: 0 22:44:17 INFO - Running post-action listener: _resource_record_post_action 22:44:17 INFO - Running post-action listener: _start_resource_monitoring 22:44:17 INFO - Starting resource monitoring. 22:44:17 INFO - ##### 22:44:17 INFO - ##### Finished create-virtualenv step (success) 22:44:17 INFO - ##### 22:44:17 INFO - ##### 22:44:17 INFO - ##### Running install step. 22:44:17 INFO - ##### 22:44:17 INFO - Running pre-action listener: _resource_record_pre_action 22:44:17 INFO - Running main action method: install 22:44:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:44:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:44:17 INFO - Reading from file tmpfile_stdout 22:44:17 INFO - Detecting whether we're running mozinstall >=1.0... 22:44:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:44:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:44:17 INFO - Reading from file tmpfile_stdout 22:44:17 INFO - Output received: 22:44:17 INFO - Usage: mozinstall [options] installer 22:44:17 INFO - Options: 22:44:17 INFO - -h, --help show this help message and exit 22:44:17 INFO - -d DEST, --destination=DEST 22:44:17 INFO - Directory to install application into. [default: 22:44:17 INFO - "/builds/slave/test"] 22:44:17 INFO - --app=APP Application being installed. [default: firefox] 22:44:17 INFO - mkdir: /builds/slave/test/build/application 22:44:17 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:44:17 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:44:33 INFO - Reading from file tmpfile_stdout 22:44:33 INFO - Output received: 22:44:33 INFO - /builds/slave/test/build/application/firefox/firefox 22:44:33 INFO - Running post-action listener: _resource_record_post_action 22:44:33 INFO - ##### 22:44:33 INFO - ##### Finished install step (success) 22:44:33 INFO - ##### 22:44:33 INFO - ##### 22:44:33 INFO - ##### Running run-tests step. 22:44:33 INFO - ##### 22:44:33 INFO - Running pre-action listener: _resource_record_pre_action 22:44:33 INFO - Running main action method: run_tests 22:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:44:33 INFO - Python 2.7.3 22:44:33 INFO - Return code: 0 22:44:33 INFO - grabbing minidump binary from tooltool 22:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:33 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 0x166b9c0>, '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 0x166c8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x166cd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:44:33 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:44:33 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:44:33 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:44:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:33 INFO - Return code: 0 22:44:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:44:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:44:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:44:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:44:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:44:34 INFO - Return code: 0 22:44:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:44:34 INFO - Using env: {'DISPLAY': ':0', 22:44:34 INFO - 'HOME': '/home/cltbld', 22:44:34 INFO - 'LANG': 'en_US.UTF-8', 22:44:34 INFO - 'LANGUAGE': 'en_US:en', 22:44:34 INFO - 'LOGNAME': 'cltbld', 22:44:34 INFO - 'MAIL': '/var/mail/cltbld', 22:44:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:44:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:34 INFO - 'MOZ_NO_REMOTE': '1', 22:44:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:34 INFO - 'NO_EM_RESTART': '1', 22:44:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:44:34 INFO - 'PWD': '/builds/slave/test', 22:44:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:44:34 INFO - 'SHELL': '/bin/bash', 22:44:34 INFO - 'SHLVL': '1', 22:44:34 INFO - 'TERM': 'linux', 22:44:34 INFO - 'TMOUT': '86400', 22:44:34 INFO - 'USER': 'cltbld', 22:44:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413', 22:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:34 INFO - '_': '/tools/buildbot/bin/python'} 22:44:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:44:34 INFO - mozversion application_buildid: 20160518214837 22:44:34 INFO - mozversion application_changeset: 0ba8107314d6e579f881399cc2ec0a6478080a90 22:44:34 INFO - mozversion application_display_name: Nightly 22:44:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:34 INFO - mozversion application_name: Firefox 22:44:34 INFO - mozversion application_remotingname: firefox 22:44:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:44:34 INFO - mozversion application_vendor: Mozilla 22:44:34 INFO - mozversion application_version: 49.0a1 22:44:34 INFO - mozversion platform_buildid: 20160518214837 22:44:34 INFO - mozversion platform_changeset: 0ba8107314d6e579f881399cc2ec0a6478080a90 22:44:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:44:34 INFO - mozversion platform_version: 49.0a1 22:44:34 INFO - using testdate: 1463636674 22:44:34 INFO - actual date: 1463636674 22:44:34 INFO - starting webserver on 'localhost:50986' 22:44:34 INFO - SUITE-START | Running 2 tests 22:44:34 INFO - TEST-START | dromaeo_css 22:44:34 INFO - operating with platform_type : linux_ 22:44:34 INFO - Initialising browser for dromaeo_css test... 22:44:34 INFO - TEST-INFO | started process 10271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAMk8vO/profile http://localhost:50986/getInfo.html) 22:44:39 INFO - PROCESS | 10271 | __metrics Screen width/height:1600/1200 22:44:39 INFO - PROCESS | 10271 | colorDepth:24 22:44:39 INFO - PROCESS | 10271 | Browser inner width/height: 1024/697 22:44:39 INFO - PROCESS | 10271 | __metrics 22:44:40 ERROR - PROCESS | 10271 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:44:41 INFO - TEST-INFO | 10271: exit 0 22:44:41 INFO - Browser initialized. 22:44:41 INFO - Running cycle 1/1 for dromaeo_css test... 22:44:41 INFO - TEST-INFO | started process 10352 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAMk8vO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:53 INFO - PROCESS | 10352 | RSS: Main: 154161152 22:44:53 INFO - PROCESS | 10352 | 22:45:44 INFO - PROCESS | 10352 | 1463636744690 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:47:56 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50986/tests/dromaeo/cssquery-ext.html) 22:47:57 INFO - PROCESS | 10352 | RSS: Main: 274571264 22:47:57 INFO - PROCESS | 10352 | 22:51:00 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-ext.html (next: http://localhost:50986/tests/dromaeo/cssquery-jquery.html) 22:51:00 INFO - PROCESS | 10352 | RSS: Main: 234606592 22:51:00 INFO - PROCESS | 10352 | 22:54:03 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50986/tests/dromaeo/cssquery-mootools.html) 22:54:03 INFO - PROCESS | 10352 | RSS: Main: 245899264 22:54:03 INFO - PROCESS | 10352 | 22:57:05 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50986/tests/dromaeo/cssquery-prototype.html) 22:57:06 INFO - PROCESS | 10352 | RSS: Main: 234553344 22:57:06 INFO - PROCESS | 10352 | 23:00:09 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50986/tests/dromaeo/cssquery-yui.html) 23:00:09 INFO - PROCESS | 10352 | RSS: Main: 215666688 23:00:09 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | Cycle 1(1): loaded http://localhost:50986/tests/dromaeo/cssquery-yui.html (next: http://localhost:50986/tests/dromaeo/cssquery-dojo.html) 23:03:12 INFO - PROCESS | 10352 | RSS: Main: 296243200 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | __start_tp_report 23:03:12 INFO - PROCESS | 10352 | _x_x_mozilla_page_load 23:03:12 INFO - PROCESS | 10352 | _x_x_mozilla_page_load_details 23:03:12 INFO - PROCESS | 10352 | |i|pagename|runs| 23:03:12 INFO - PROCESS | 10352 | |0;dojo.html;2691.219546331564;2707.4179051503925;2733.453309338133;2737.931551711208;2761.917142485725;4676.976615116928;4738.336632871396;4743.928841067378;4827.637927155464;4867.099586576482;4738.9052218955685;4784.162771514988;4786.282057691348;4793.904121917568;4800.9519904800945;4311.956880431195;4368.672349573779;4422.579854913778;4456.844010459635;4541.318802179667;4423.225935461298;4444.599986001259;4454.287314029756;4463.330500424946;4505.098980203956;4313.611774940239;4357.172137293904;4376.343548467724;4394.231009573312;4405.713628614129;4334.176506463762;4364.869053928387;4368.5194628590825;4439.600435960779;4452.109578084396;4394.362817391492;4401.823927042915;4409.037978099747;4439.445069366329;4452.265376212921;4561.429821272341;4661.603763680057;4759.095771803346;4809.374781278447;4812.614990800727;5135.589152867761;5163.199704045879;5212.478752124795;5214.634975551713;5216.921746173811;5136.409312929024;5179.093658609766;5239.187925871496;5243.895122097575;5246.8688282792855;4677.95322046782;4783.115123702126;4813.15769740301;4867.853964381039;4880.707157570557;5291.788328466895;5294.285271488377;5314.7608357624;5366.946330536723;5377;5078.08594453002;5084.669496482717;5097.923531147036;5148.6910785353;5151.7166555839785;4976.178930476503;5025.3718285214345;5080.74596270185;5081.796728130834;5171.612129090333;5012.172991456368;5029.346184995927;5040.521150490697;5046.1926091825135;5094.77073531692;5003.349564556657;5014.222795466709;5016.523430274118;5025.14572522672;5027.924581131286;4194.8112334945;4236.682248831288;4273.572642735764;4297.290946993711;4309.987153019038;3736.626337366242;3742.2702572998005;3742.62573742629;3785.981070094632;3819.1216020315483;4473.664475164375;4473.910521789546;4483.1843103543115;4512.323151527232;4535.7732113394195;4994.5754610858485;5142.1258386073705;5171.637985341065;5186.325777648957;5208.114620514521;4494.348319493655;4500.842470513517;4511.70673906195;4517.435320584927;4558.6581006423985;4636.79134438951;4650.651201159926;4653.022865198279;4656.254999200111;4696.38946936896;4669.486356500782;4723.197056500335;4771.355866957916;4793.017431426619;4811.398575178103;5076.873078172927;5085.64400491962;5096.210087436305;5099.51554602327;5147.356580427447;4729;4788.257820037899;4802.711837289774;4808.110499557593;4809.639277054165;4990.226514890198;5019.648624596243;5033.84898453047;5054.545090941833;5067.847964561069;5124.052050370693;5137.794488220429;5147.40804807452;5151.8196863109615;5204.193350030751;4871.707697538159;4937.432195297587;4978.701277923191;4983.25251212321;4988.451270360185;4950.727709975986;4995.550400463976;5008.373953255843;5010.899782004412;5065.341505604395;4929.334539837076;4944.703317800943;4969.776360063734;4995.125852975787;5010.423801262901;4941.50584941503;5018;5050.444451110301;5097.847064588068;5105.617078719037;4809.735464549343;4857.0528746894015;4867.075255701406;4874.926876096933;4908.533689299511 23:03:12 INFO - PROCESS | 10352 | |1;ext.html;551.9668819870808;594.5392320951262;595.3689089565058;601.2754630670041;613.221209064488;28271.29321766957;28641.283967900814;31030.37939241214;31057;41799.79100104503;40396.19207615846;42138.789306053506;42954.57045429545;42958.35562466565;42965;7888.645010974506;8086.7465542840955;8089.110197878229;8092.312153466933;8096.31181349583;7478.476506644537;7926.682932682715;8066.677332906706;10456.424896630682;10702.250842441032;10087.64693235737;10470.21473389495;10478.947605261963;10504.527296271648;10513.106385957202;10045.698629041104;10102.494875256209;10105.141063009616;10106.191504679608;10108.747281317952;10177.949110254476;10280.177585793193;10287.639932602398;10325.380477171393;10337.483125843753;41209;41330.38004429936;41430.585694142974;41592;41637.79181104075;3333.283344081034;3338.2488939988307;3350.2629421527226;3365.2428203654226;3380.4760262159402;30537.694623053707;31208.72141201917;31213.687863121533;31270.374592508022;31490;3542.6103128655827;3566.2154326048226;3601.837917293728;3607.858801208294;3610.711143108545;287786;289118;289151;289858;290212;63815.36184638187;63818.68090659517;64170;64195;64483.35516644867;67290.66354668293;67412;67568;67604.3239567598;67625;15701.371945122068;16273.349066037226;16286.592835178979;16299.185040747916;16309.83690163107;12115.454804533818;12152.331621760712;12159.027277817753;12164.2701437914;12165.817512737316;20000.499987500138;20154.093065812074;20270.885101319273;20306.492337691678;20346.796532034492;7222.458315626349;7311.341979221896;7539.472236943361;7568.810779730551;7666.8849967250535;839.5382539603194;877.5743764274322;1001.5142655811925;1024.9077583017415;1037.99481002596;28448;28830.135095947157;28884.566731499046;29182.562261566094;29453.85273073621;1010.1565193063727;1044.4621020174616;1052.2791887557048;1121.9943900280446;1124.5108377855659;15152;15270.16014119234;15390;15431.305591248421;15559.688806223812;394991;759982;782641;784858;786871;54795.17807232895;54989.450105498436;55027.17459238115;55285.44714552884;55388.44611553833;1144.4792619358338;1245.769532636465;1288.5425673885823;1297.7923532234797;1303.9413226404833;27982;30107.698923011365;30111.397772044875;30600.540991884693;30625.23436914006;42063.36904946367;42179.1564168721;42609.78695106505;42634;42665.36001959911;329593;332204;332861;333316;334297;22970.425739355982;26302.736972630795;42107.789461053726;42795.786021069696;42800.57199427966;42534;47339;47416.52583474259;48266.27600586064;48413.5158648409;39149.80425097857;39222.2155556893;39548.418063277146;40476.1904761909;40524;38826.22347552977;39467.605323947544;40106.799466002485;40193.79903100466;40483;39026;39274.6072539271;39420.80289598534;40149;40226.59773402228;33609.49585756167;35957.460638090975;36346.404807152874;36820.63179368172;39524.20951580895;27090;27169;27516.449671006867;27529.31176720598;27682.307942301602 23:03:12 INFO - PROCESS | 10352 | |2;jquery.html;372.84713267560295;380.8895420328105;386.19093000164645;387.0710295291302;397.67788091645787;16402.671946561062;17533.298668053296;18239.361622343222;18298.817011829877;18324.175412106455;15848.920755396206;16866.325346986137;16870.915645421785;17596;17778.28886844525;5090.440051594331;5155.097857874857;5246.947530524703;5271.604629652791;5272.710000949965;3675.7426980111404;3860.3823388257883;4026.516817981846;5950.642961422307;5957.404259574029;5842.561807864406;5887.411258874121;5942.524598032169;6006.579539432241;6020.698965051752;5925.851853703649;5929.762809487637;5981;5993.071073983518;6023.608465449754;5374.113271310229;5375;5378.650387724785;5383.111786555213;5387.0303345397815;22258.332250032527;23555.646665300035;23582.764172358227;23624.29127126189;24542;2097.3078883968406;2171.880546569938;2182.5962196993605;2488.228649118779;2547.7070136934303;13938.233397163152;14488.348024338824;14556.927215363856;14983.475578354813;15265.007774494623;1737.9131043447776;1839.5283772981695;1963.0969753913216;1966.7934866838918;1978.8416926645978;77676.61161694211;81168;81600.59199703964;82764;84756.15243847544;16917;16972.321107155574;17006.829931700526;17099.91450042767;17164.313427462763;29777.85111074474;30045.399092018037;30205.244868878013;30355.848220759195;30381.848090759406;8690.217880390795;8948.463092214488;9025.232855207381;9126.08739126082;9275.768105797275;7233.45749068822;7494.063242094739;7511.924880751122;7576.469647124759;7608.2011388804485;8633.179847914438;8651.134886511425;8687.39188256816;8815.471071735716;8820.25027872638;3130.3306898099745;3392.9321413572084;3408.5062810619315;3421.469672200813;3426.1605906553054;818.3126174013738;859.1494420523761;877.6664867350366;903.2545918746773;913.8492148795507;16812.915935420246;16832.24254908532;17081.231344589523;17154.228059737343;17304.307827687004;896;960.9183219426287;975.7024107647094;984.1831280037552;992.374803873555;9812.086643104669;9884.406935583887;10070.345427547187;10073.546690398949;10091.436495782284;412435;416813;417052;419926;425131;82503;83319;83407;83796.58101709452;86189.5690521556;986.6694657289888;1001.8697569316088;1004.2367800471549;1023.1763430438419;1023.9692809215735;15416.691666166838;15544.611384715472;15695;15838;16059;26048;26608.334791630365;28356;34287.31425371528;36999.8150009259;48855.75572122117;49619.25571116364;53485.465145349604;53612;54092.72953635207;8110.635021423932;8115.107338192676;8118.107008228972;8288.295494883003;8501.404901656824;9194.678186263449;9438.622455101719;11934.343611101473;12018.399080046138;12385.442655080724;9984.35101718386;10411.594434751514;10457.163426925676;10533.683989480327;10598.099161571352;10480.947595261974;10491.318064325795;10517.894821051997;10603.628872989717;10612.257141999986;10618;10629.681109566725;10748.677539673823;10781.730456738644;10843.837342440027;7757.806054848674;7848.175941526058;7956.801079973047;8233.382496312921;8250.834983300421;8142.389320801082;9760.902390976;10630.574777008833;10641.095506881693;10907.509162087883 23:03:12 INFO - PROCESS | 10352 | |3;mootools.html;1276.1959965221909;1475.5425817996422;1495.5214331413945;1659.1787065402627;1831.1576674729624;6997;7893.684252629895;8134.064582572999;8140.348772098233;8284.088750237484;8046.476978996361;8226.753197404072;8228.2594566489;8229.135940726228;8230.46501977371;2283.817294616435;2311.861288322706;2312.028947841902;2313.1556981701724;2314.0512389920136;2256.6953461282765;2304.28567144185;2305.1931823861682;2306.1582522379376;2308.90764369425;2244.4052326133587;2258.627326491135;2266.433391652087;2303.9308820735405;2314.4908120213527;2235.10595761695;2252.4594097416584;2255.751867294596;2259.7062381890332;2259.7853203945597;2193.7586865444787;2197.110170380998;2198.428408613756;2201.0535469748;2202.713647225866;7891.447598668038;8130.674773009073;8130.674773009073;8190.7952301192945;8216.424850260484;4678.883027924295;4785.66500344976;4795.640326975491;4799.520047995207;4803.3035209894715;3114.7196752292402;3121.953170702441;3176.5552822604855;3179.173414912116;3215.565898603705;1022.7443139215196;1036.3471013261596;1036.4244851543908;1042.525650828871;1049.5906596427399;10255.69232923006;10605.575776968912;10609.73475663107;10911.727206819814;10924.945375273073;4211.305134652809;4257.318828987347;4326.242907491176;4362.585554372329;4371.256886329332;8723.956380218058;8741.388102832869;8749.03760586335;8770.429922055046;8783.121687831293;4820.301056346881;4825.517448255217;4826.831060912851;4829.1307564638;4832.855014349575;3831.6934645228316;4295.140971805651;4433.7118087324225;4468.553144685506;4468.888277793016;3532.523109380252;3557.395242808677;3564.055525285801;3628.582712987987;3655.908602284964;1067.7864427114453;1071.5178169823455;1077.5743581285349;1087.3258579680648;1090;672.5023482622823;755.1693137548652;876.2858270509514;877.723517092114;880.8062226310202;4968.22992436173;5254.211868219798;5338.866528336823;5344.8663783404945;5372.489613486712;913.0823522359985;918.2699753695828;922.4787994782927;935.3078721745995;945.0360632155164;3717.4423836424753;3857.324968130566;3876.8061596920606;3903.2583809076186;3929.980350098231;9282.72151835446;9420.811583768424;9689.70930872075;9706.272029597809;9713.417194968324;9660.37207581519;9721.56252968604;9739.123478886933;9742.171915387271;9749.317547771589;7357.153927298322;7367.557946523226;7388.704451821867;7467.962660186773;7507.361874240642;5298.682079075268;5328.7602057906715;5354.3574771027725;5368.785248590013;5380.677159370293;9169.266458683423;9332.20676242527;9410.811783764424;9426.057394260793;9431.198348140484;9403.529823508934;9434.339596228197;9507.572159252712;9568.76078098053;9604.423734575948;6857.7256909723055;7176.246494118042;7178.533395329287;7255.2019277878335;7270.563766174047;7602.885956710543;7729.4202934779;7737.651805668871;7746.380289576708;7752.7674169775;6668.199816022108;6774.932250677627;6960.547564408297;7043.894341584983;7297.635118244173;6951.756688515878;6980.7207711693;7009.5230489728365;7280.599567023864;7311.707531698672;6277.874442511033;6320.6839658016;6333.081703153109;6341.651209183539;6345.936540634534;4549.886252843706;4798.1123492154065;4812.109759694467;4839.008003359232;4858.611311095033;4911.803527858846;4920.852374428773;4962.255661650781;4966.627502937222;5047.570956468741 23:03:12 INFO - PROCESS | 10352 | |4;prototype.html;298.7938322557435;301.20782342439395;304.45958423797754;317.29402080371165;321.85516517567083;4653.581177694007;4769.976150119244;4838.830640927568;4877.073555945404;4894.853154405369;4603.941093548486;4737.928931066037;4744.193487107192;4756.54812792785;4762.809297675582;3767.8492860285623;3790.564085130204;3794.715396345285;3795.2030073684423;3838.4626152338697;3561.6972557332656;3616.4575313702894;3734.906627334311;3817.9045523861846;3828.549728173957;3706.666400023997;3734.3112958669712;3743.4010558310674;3743.4571987061995;3754.81225938702;3719.6280371962857;3751.930699750558;3752.043228976621;3756.69946404287;3760.931487681764;3320.8671653133847;3331.700146986783;3339.2653616204398;3351.245969656832;3369.8989030328885;4499.325101234808;4571.3600095986585;4575.084983003379;4620.445546534404;4665.930011049837;4265.402843601898;4270.145970805851;4295.591918767712;4299.72051816634;4302.397664326997;4848.418189817209;4983.551480366785;4988.925166122474;5011.1481048221885;5028.723420211887;3629.782213067198;3635.618669771304;3638.872639457606;3640.799756013418;3642.1258897864586;5279.498447647467;5310.389305229909;5313.362396512404;5314.627976041678;5320.6275560710765;3735.402335626287;3741.120836603396;3741.831617577161;3742.288965096623;3749.981250093732;3553.756185335112;3760.8119594020736;3774.2640185163627;3774.339490589136;3786.053486628343;4267.231898258343;4270.316749320094;4271.252530807096;4275.593818587229;4278.572142785759;4779.95220047802;4992.425871024806;5002.274670172877;5022.296878437021;5046.924296135561;1213.9028877689764;1260.7289432771997;1262.8484581850237;1273.7579859826603;1274.8972139099797;811.4076723991401;813.353384059674;821.954792486419;836.3351135847016;841.2975165736556;3968.4245784361106;3986.86045988389;4021.5174179098694;4046.4537287465814;4048.4129801178665;4538.977305113453;4597.839075632337;4606.447226332862;4607.424071991001;4619.491855895849;4030.1386015928433;4041.333180025263;4042.1915616876154;4042.413849991735;4061.3501839705505;2275.613145765224;2422.9636555451684;2432.1122790181457;2444.633305004228;2447.9388015299405;4346.391505189276;4384.276594361894;4392.956070439254;4403.889902752393;4409.941614012678;4646.465656449484;4687.6249900007915;4702.905941881211;4705.270683044134;4758.762061896891;4075.694322925792;4267.274563324242;4316.223079845595;4326.978365108154;4360.869173924787;4551.40831691878;4575.794089265924;4591.816327346869;4605.171069207575;4621.237495813153;4458.6878918475395;4472.3515090311075;4501.8970352263905;4503.950702430321;4512.3908272382805;4180.7909604519045;4310.655147588122;4341.630961368192;4348.695591308578;4405.31717583775;3336.2993771308297;3345.2974875276464;3346.4478361071124;3356.56364672591;3356.6139893912514;4585.243434833348;4609.861704148881;4617.145828021827;4624.514425985358;4926.078829256665;4790.9281360780315;4942.5304596063315;4946.678465899705;4969.1800852860315;4997.475265097112;4970.353853998957;4980.178270585313;4986.850394488171;4999.350344317518;5015.473375295542;4618.745968971739;4940.481249468754;4949.950500494949;4968.428630707371;4968.652194346506;4992.550670439678;5017.598592112695;5029.145045342228;5040.873978150576;5064.392272927273;4907.312976183266;4921.237208232729;4926.802927882844;4937.703737775745;4938.111139994836 23:03:12 INFO - PROCESS | 10352 | |5;yui.html;750.7785203365007;783.0759703549811;791.3708601661679;799.1209669363697;812.9756107316782;12201.084918631104;12559;12698.365081745898;12744.681382965442;12752.171108877916;12108.878911210886;12726.36368181592;12744.872551274484;12752.617421477371;12777.872221277785;853.5902766672001;866.0473479172922;873.5544872115236;874.1215078845783;874.2612492443875;853.6116067189445;856.7258477287271;857.1985193843776;857.2713193785295;857.6012154348227;904.2178515584005;908.7092130518237;909.4725059465494;910.0126362896266;910.7632015675906;906.8277027364812;906.9501177435238;907.0838453162321;907.5008745190119;907.6505545365015;658.6607896933035;658.7694306992562;658.9967050164719;659.0805825872881;659.3867703036175;11432.599859004895;11433;11467.942660286728;11485.138614603939;12200.511979520808;10411.94794026025;10505.474726263732;10540;10735.980081892209;10822.296550724177;9097.954510227406;9158.358914875962;9221.308401869886;9359.672411465566;9485.573149208301;3252.4633435483115;3270.1824543864036;3303.537504749337;3306.206510437502;3395.898123056312;44978.325325120146;45242.77378613119;45301.09397812058;45698.31452528215;45757.62727118192;7863.292303692695;8083.636236369378;8213.712520061768;8362.372822038371;8405.747827565183;15789.684206316038;15816.446424375092;15822.446214382442;15827.366905323657;15842.60393490172;6341.714622841983;6582.670866456658;6722.697478613474;6753.797386078426;6953.895691564529;3583.7670551414076;3634.34581775283;3664.0656632558525;3665.70674346057;3803.953912674037;2828.250513613894;2938.103878317091;2940.2355387599378;2941.808782429135;2944.27865173034;486.0667518364748;486.3191531855431;495.8760309922519;512.9717865517432;514.7452011254453;607.1651479216077;635.1171871099176;636.2778246689948;638.664701031962;641.5348872067714;7675.424343174284;8099.230573095547;8144.267015968591;8156;8275.022817158475;623.2529656371258;625.2621906150786;629.2700467457727;636.8694417644372;642.3159335307976;3511.2801875615437;3643.690286325639;3770.1517158639085;3771.641694039062;3779;53455.19817202745;54224.45775542273;54518;54747.72626136924;54845.72577137089;45883.5411645886;48246.27630585544;48671.78320542015;48857.26714099292;49696.751516242184;41662.583374166476;43712.3443148353;44091.338629920574;45650.08699825985;46366.30450543245;8281.627326770189;8357.247847693738;8416.831663366576;8485.618147183268;8547.786305342415;30943.226419339695;31392.372152556363;31415;31997.520037198996;32178.35643287168;37658.62341376551;40960.79519602383;40966.590334096276;41183.176336472505;42278.154436911704;10149.086582207638;10163.288569800043;10175.643852465128;10243.129334006693;10251.384916904712;11346.319220846775;11493.942530287295;11506.94246528762;11643.53425862956;12208.206466579644;11142.665720028088;11297.152713546353;11315.547378104784;11498;11921.34432606215;10985.285956412808;11039.337639741641;11439.714007149887;11457.59898403552;11569.363684997406;10889.945550272198;11361.715957101138;11387.886121138683;11630.825537616773;11684.415779210836;8986.820263594822;9141.177294043302;9164.266858651159;9196.770080748034;9631.518424078908;7966.203379661987;8255.25702686761;8391.832163356821;8441.788955276168;8597.398182127441 23:03:12 INFO - PROCESS | 10352 | __end_tp_report 23:03:12 INFO - PROCESS | 10352 | __start_cc_report 23:03:12 INFO - PROCESS | 10352 | _x_x_mozilla_cycle_collect,1133 23:03:12 INFO - PROCESS | 10352 | __end_cc_report 23:03:12 INFO - PROCESS | 10352 | __startTimestamp1463637792943__endTimestamp 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | ------- Summary: start ------- 23:03:12 INFO - PROCESS | 10352 | Number of tests: 6 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#0] dojo.html Cycles:165 Average:4737.35 Median:4827.64 stddev:493.04 (10.2%) stddev-sans-first:467.70 23:03:12 INFO - PROCESS | 10352 | Values: 2691.2 2707.4 2733.5 2737.9 2761.9 4677.0 4738.3 4743.9 4827.6 4867.1 4738.9 4784.2 4786.3 4793.9 4801.0 4312.0 4368.7 4422.6 4456.8 4541.3 4423.2 4444.6 4454.3 4463.3 4505.1 4313.6 4357.2 4376.3 4394.2 4405.7 4334.2 4364.9 4368.5 4439.6 4452.1 4394.4 4401.8 4409.0 4439.4 4452.3 4561.4 4661.6 4759.1 4809.4 4812.6 5135.6 5163.2 5212.5 5214.6 5216.9 5136.4 5179.1 5239.2 5243.9 5246.9 4678.0 4783.1 4813.2 4867.9 4880.7 5291.8 5294.3 5314.8 5366.9 5377.0 5078.1 5084.7 5097.9 5148.7 5151.7 4976.2 5025.4 5080.7 5081.8 5171.6 5012.2 5029.3 5040.5 5046.2 5094.8 5003.3 5014.2 5016.5 5025.1 5027.9 4194.8 4236.7 4273.6 4297.3 4310.0 3736.6 3742.3 3742.6 3786.0 3819.1 4473.7 4473.9 4483.2 4512.3 4535.8 4994.6 5142.1 5171.6 5186.3 5208.1 4494.3 4500.8 4511.7 4517.4 4558.7 4636.8 4650.7 4653.0 4656.3 4696.4 4669.5 4723.2 4771.4 4793.0 4811.4 5076.9 5085.6 5096.2 5099.5 5147.4 4729.0 4788.3 4802.7 4808.1 4809.6 4990.2 5019.6 5033.8 5054.5 5067.8 5124.1 5137.8 5147.4 5151.8 5204.2 4871.7 4937.4 4978.7 4983.3 4988.5 4950.7 4995.6 5008.4 5010.9 5065.3 4929.3 4944.7 4969.8 4995.1 5010.4 4941.5 5018.0 5050.4 5097.8 5105.6 4809.7 4857.1 4867.1 4874.9 4908.5 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#1] ext.html Cycles:180 Average:59826.15 Median:3372.86 stddev:130993.55 (3883.8%) stddev-sans-first:131285.42 23:03:12 INFO - PROCESS | 10352 | Values: 552.0 594.5 595.4 601.3 613.2 28271.3 28641.3 31030.4 31057.0 41799.8 40396.2 42138.8 42954.6 42958.4 42965.0 7888.6 8086.7 8089.1 8092.3 8096.3 7478.5 7926.7 8066.7 10456.4 10702.3 10087.6 10470.2 10478.9 10504.5 10513.1 10045.7 10102.5 10105.1 10106.2 10108.7 10177.9 10280.2 10287.6 10325.4 10337.5 41209.0 41330.4 41430.6 41592.0 41637.8 3333.3 3338.2 3350.3 3365.2 3380.5 30537.7 31208.7 31213.7 31270.4 31490.0 3542.6 3566.2 3601.8 3607.9 3610.7 287786.0 289118.0 289151.0 289858.0 290212.0 63815.4 63818.7 64170.0 64195.0 64483.4 67290.7 67412.0 67568.0 67604.3 67625.0 15701.4 16273.3 16286.6 16299.2 16309.8 12115.5 12152.3 12159.0 12164.3 12165.8 20000.5 20154.1 20270.9 20306.5 20346.8 7222.5 7311.3 7539.5 7568.8 7666.9 839.5 877.6 1001.5 1024.9 1038.0 28448.0 28830.1 28884.6 29182.6 29453.9 1010.2 1044.5 1052.3 1122.0 1124.5 15152.0 15270.2 15390.0 15431.3 15559.7 394991.0 759982.0 782641.0 784858.0 786871.0 54795.2 54989.5 55027.2 55285.4 55388.4 1144.5 1245.8 1288.5 1297.8 1303.9 27982.0 30107.7 30111.4 30600.5 30625.2 42063.4 42179.2 42609.8 42634.0 42665.4 329593.0 332204.0 332861.0 333316.0 334297.0 22970.4 26302.7 42107.8 42795.8 42800.6 42534.0 47339.0 47416.5 48266.3 48413.5 39149.8 39222.2 39548.4 40476.2 40524.0 38826.2 39467.6 40106.8 40193.8 40483.0 39026.0 39274.6 39420.8 40149.0 40226.6 33609.5 35957.5 36346.4 36820.6 39524.2 27090.0 27169.0 27516.4 27529.3 27682.3 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#2] jquery.html Cycles:180 Average:26695.31 Median:2123.29 stddev:69100.94 (3254.4%) stddev-sans-first:69266.52 23:03:12 INFO - PROCESS | 10352 | Values: 372.8 380.9 386.2 387.1 397.7 16402.7 17533.3 18239.4 18298.8 18324.2 15848.9 16866.3 16870.9 17596.0 17778.3 5090.4 5155.1 5246.9 5271.6 5272.7 3675.7 3860.4 4026.5 5950.6 5957.4 5842.6 5887.4 5942.5 6006.6 6020.7 5925.9 5929.8 5981.0 5993.1 6023.6 5374.1 5375.0 5378.7 5383.1 5387.0 22258.3 23555.6 23582.8 23624.3 24542.0 2097.3 2171.9 2182.6 2488.2 2547.7 13938.2 14488.3 14556.9 14983.5 15265.0 1737.9 1839.5 1963.1 1966.8 1978.8 77676.6 81168.0 81600.6 82764.0 84756.2 16917.0 16972.3 17006.8 17099.9 17164.3 29777.9 30045.4 30205.2 30355.8 30381.8 8690.2 8948.5 9025.2 9126.1 9275.8 7233.5 7494.1 7511.9 7576.5 7608.2 8633.2 8651.1 8687.4 8815.5 8820.3 3130.3 3392.9 3408.5 3421.5 3426.2 818.3 859.1 877.7 903.3 913.8 16812.9 16832.2 17081.2 17154.2 17304.3 896.0 960.9 975.7 984.2 992.4 9812.1 9884.4 10070.3 10073.5 10091.4 412435.0 416813.0 417052.0 419926.0 425131.0 82503.0 83319.0 83407.0 83796.6 86189.6 986.7 1001.9 1004.2 1023.2 1024.0 15416.7 15544.6 15695.0 15838.0 16059.0 26048.0 26608.3 28356.0 34287.3 36999.8 48855.8 49619.3 53485.5 53612.0 54092.7 8110.6 8115.1 8118.1 8288.3 8501.4 9194.7 9438.6 11934.3 12018.4 12385.4 9984.4 10411.6 10457.2 10533.7 10598.1 10480.9 10491.3 10517.9 10603.6 10612.3 10618.0 10629.7 10748.7 10781.7 10843.8 7757.8 7848.2 7956.8 8233.4 8250.8 8142.4 9760.9 10630.6 10641.1 10907.5 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#3] mootools.html Cycles:180 Average:5285.90 Median:5150.89 stddev:2917.23 (56.6%) stddev-sans-first:2909.84 23:03:12 INFO - PROCESS | 10352 | Values: 1276.2 1475.5 1495.5 1659.2 1831.2 6997.0 7893.7 8134.1 8140.3 8284.1 8046.5 8226.8 8228.3 8229.1 8230.5 2283.8 2311.9 2312.0 2313.2 2314.1 2256.7 2304.3 2305.2 2306.2 2308.9 2244.4 2258.6 2266.4 2303.9 2314.5 2235.1 2252.5 2255.8 2259.7 2259.8 2193.8 2197.1 2198.4 2201.1 2202.7 7891.4 8130.7 8130.7 8190.8 8216.4 4678.9 4785.7 4795.6 4799.5 4803.3 3114.7 3122.0 3176.6 3179.2 3215.6 1022.7 1036.3 1036.4 1042.5 1049.6 10255.7 10605.6 10609.7 10911.7 10924.9 4211.3 4257.3 4326.2 4362.6 4371.3 8724.0 8741.4 8749.0 8770.4 8783.1 4820.3 4825.5 4826.8 4829.1 4832.9 3831.7 4295.1 4433.7 4468.6 4468.9 3532.5 3557.4 3564.1 3628.6 3655.9 1067.8 1071.5 1077.6 1087.3 1090.0 672.5 755.2 876.3 877.7 880.8 4968.2 5254.2 5338.9 5344.9 5372.5 913.1 918.3 922.5 935.3 945.0 3717.4 3857.3 3876.8 3903.3 3930.0 9282.7 9420.8 9689.7 9706.3 9713.4 9660.4 9721.6 9739.1 9742.2 9749.3 7357.2 7367.6 7388.7 7468.0 7507.4 5298.7 5328.8 5354.4 5368.8 5380.7 9169.3 9332.2 9410.8 9426.1 9431.2 9403.5 9434.3 9507.6 9568.8 9604.4 6857.7 7176.2 7178.5 7255.2 7270.6 7602.9 7729.4 7737.7 7746.4 7752.8 6668.2 6774.9 6960.5 7043.9 7297.6 6951.8 6980.7 7009.5 7280.6 7311.7 6277.9 6320.7 6333.1 6341.7 6345.9 4549.9 4798.1 4812.1 4839.0 4858.6 4911.8 4920.9 4962.3 4966.6 5047.6 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#4] prototype.html Cycles:180 Average:4009.84 Median:4388.62 stddev:1149.40 (26.2%) stddev-sans-first:1118.37 23:03:12 INFO - PROCESS | 10352 | Values: 298.8 301.2 304.5 317.3 321.9 4653.6 4770.0 4838.8 4877.1 4894.9 4603.9 4737.9 4744.2 4756.5 4762.8 3767.8 3790.6 3794.7 3795.2 3838.5 3561.7 3616.5 3734.9 3817.9 3828.5 3706.7 3734.3 3743.4 3743.5 3754.8 3719.6 3751.9 3752.0 3756.7 3760.9 3320.9 3331.7 3339.3 3351.2 3369.9 4499.3 4571.4 4575.1 4620.4 4665.9 4265.4 4270.1 4295.6 4299.7 4302.4 4848.4 4983.6 4988.9 5011.1 5028.7 3629.8 3635.6 3638.9 3640.8 3642.1 5279.5 5310.4 5313.4 5314.6 5320.6 3735.4 3741.1 3741.8 3742.3 3750.0 3553.8 3760.8 3774.3 3774.3 3786.1 4267.2 4270.3 4271.3 4275.6 4278.6 4780.0 4992.4 5002.3 5022.3 5046.9 1213.9 1260.7 1262.8 1273.8 1274.9 811.4 813.4 822.0 836.3 841.3 3968.4 3986.9 4021.5 4046.5 4048.4 4539.0 4597.8 4606.4 4607.4 4619.5 4030.1 4041.3 4042.2 4042.4 4061.4 2275.6 2423.0 2432.1 2444.6 2447.9 4346.4 4384.3 4393.0 4403.9 4409.9 4646.5 4687.6 4702.9 4705.3 4758.8 4075.7 4267.3 4316.2 4327.0 4360.9 4551.4 4575.8 4591.8 4605.2 4621.2 4458.7 4472.4 4501.9 4504.0 4512.4 4180.8 4310.7 4341.6 4348.7 4405.3 3336.3 3345.3 3346.4 3356.6 3356.6 4585.2 4609.9 4617.1 4624.5 4926.1 4790.9 4942.5 4946.7 4969.2 4997.5 4970.4 4980.2 4986.9 4999.4 5015.5 4618.7 4940.5 4950.0 4968.4 4968.7 4992.6 5017.6 5029.1 5040.9 5064.4 4907.3 4921.2 4926.8 4937.7 4938.1 23:03:12 INFO - PROCESS | 10352 | 23:03:12 INFO - PROCESS | 10352 | [#5] yui.html Cycles:180 Average:12839.43 Median:47306.29 stddev:14958.49 (31.6%) stddev-sans-first:14972.91 23:03:12 INFO - PROCESS | 10352 | Values: 750.8 783.1 791.4 799.1 813.0 12201.1 12559.0 12698.4 12744.7 12752.2 12108.9 12726.4 12744.9 12752.6 12777.9 853.6 866.0 873.6 874.1 874.3 853.6 856.7 857.2 857.3 857.6 904.2 908.7 909.5 910.0 910.8 906.8 907.0 907.1 907.5 907.7 658.7 658.8 659.0 659.1 659.4 11432.6 11433.0 11467.9 11485.1 12200.5 10411.9 10505.5 10540.0 10736.0 10822.3 9098.0 9158.4 9221.3 9359.7 9485.6 3252.5 3270.2 3303.5 3306.2 3395.9 44978.3 45242.8 45301.1 45698.3 45757.6 7863.3 8083.6 8213.7 8362.4 8405.7 15789.7 15816.4 15822.4 15827.4 15842.6 6341.7 6582.7 6722.7 6753.8 6953.9 3583.8 3634.3 3664.1 3665.7 3804.0 2828.3 2938.1 2940.2 2941.8 2944.3 486.1 486.3 495.9 513.0 514.7 607.2 635.1 636.3 638.7 641.5 7675.4 8099.2 8144.3 8156.0 8275.0 623.3 625.3 629.3 636.9 642.3 3511.3 3643.7 3770.2 3771.6 3779.0 53455.2 54224.5 54518.0 54747.7 54845.7 45883.5 48246.3 48671.8 48857.3 49696.8 41662.6 43712.3 44091.3 45650.1 46366.3 8281.6 8357.2 8416.8 8485.6 8547.8 30943.2 31392.4 31415.0 31997.5 32178.4 37658.6 40960.8 40966.6 41183.2 42278.2 10149.1 10163.3 10175.6 10243.1 10251.4 11346.3 11493.9 11506.9 11643.5 12208.2 11142.7 11297.2 11315.5 11498.0 11921.3 10985.3 11039.3 11439.7 11457.6 11569.4 10889.9 11361.7 11387.9 11630.8 11684.4 8986.8 9141.2 9164.3 9196.8 9631.5 7966.2 8255.3 8391.8 8441.8 8597.4 23:03:12 INFO - PROCESS | 10352 | -------- Summary: end -------- 23:03:12 INFO - PROCESS | 10352 | 23:03:13 INFO - TEST-INFO | 10352: exit 0 23:03:13 INFO - TEST-OK | dromaeo_css | took 1118917ms 23:03:13 INFO - TEST-START | kraken 23:03:13 INFO - operating with platform_type : linux_ 23:03:13 INFO - Initialising browser for kraken test... 23:03:13 INFO - TEST-INFO | started process 10529 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJHreyt/profile http://localhost:50986/getInfo.html) 23:03:18 INFO - PROCESS | 10529 | __metrics Screen width/height:1600/1200 23:03:18 INFO - PROCESS | 10529 | colorDepth:24 23:03:18 INFO - PROCESS | 10529 | Browser inner width/height: 1024/697 23:03:18 INFO - PROCESS | 10529 | __metrics 23:03:19 ERROR - PROCESS | 10529 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:03:20 INFO - TEST-INFO | 10529: exit 0 23:03:20 INFO - Browser initialized. 23:03:20 INFO - Running cycle 1/1 for kraken test... 23:03:20 INFO - TEST-INFO | started process 10613 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJHreyt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:31 INFO - PROCESS | 10613 | RSS: Main: 154099712 23:03:31 INFO - PROCESS | 10613 | 23:03:34 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50986/tests/kraken/driver.html?testName=audio-beat-detection) 23:03:35 INFO - PROCESS | 10613 | RSS: Main: 210210816 23:03:35 INFO - PROCESS | 10613 | 23:03:38 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50986/tests/kraken/driver.html?testName=audio-dft) 23:03:39 INFO - PROCESS | 10613 | RSS: Main: 198979584 23:03:39 INFO - PROCESS | 10613 | 23:03:42 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50986/tests/kraken/driver.html?testName=audio-fft) 23:03:43 INFO - PROCESS | 10613 | RSS: Main: 266678272 23:03:43 INFO - PROCESS | 10613 | 23:03:45 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50986/tests/kraken/driver.html?testName=audio-oscillator) 23:03:45 INFO - PROCESS | 10613 | RSS: Main: 176123904 23:03:45 INFO - PROCESS | 10613 | 23:03:48 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50986/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:03:48 INFO - PROCESS | 10613 | RSS: Main: 180387840 23:03:48 INFO - PROCESS | 10613 | 23:03:52 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50986/tests/kraken/driver.html?testName=imaging-darkroom) 23:03:52 INFO - PROCESS | 10613 | RSS: Main: 215830528 23:03:52 INFO - PROCESS | 10613 | 23:03:56 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50986/tests/kraken/driver.html?testName=imaging-desaturate) 23:03:57 INFO - PROCESS | 10613 | RSS: Main: 214941696 23:03:57 INFO - PROCESS | 10613 | 23:04:00 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50986/tests/kraken/driver.html?testName=json-parse-financial) 23:04:00 INFO - PROCESS | 10613 | RSS: Main: 215580672 23:04:00 INFO - PROCESS | 10613 | 23:04:02 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50986/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:04:03 INFO - PROCESS | 10613 | RSS: Main: 203923456 23:04:03 INFO - PROCESS | 10613 | 23:04:05 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:04:05 INFO - PROCESS | 10613 | RSS: Main: 207216640 23:04:05 INFO - PROCESS | 10613 | 23:04:07 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:04:08 INFO - PROCESS | 10613 | RSS: Main: 216502272 23:04:08 INFO - PROCESS | 10613 | 23:04:11 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:04:12 INFO - PROCESS | 10613 | RSS: Main: 224550912 23:04:12 INFO - PROCESS | 10613 | 23:04:15 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:04:15 INFO - PROCESS | 10613 | RSS: Main: 221532160 23:04:15 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | Cycle 1(1): loaded http://localhost:50986/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50986/tests/kraken/driver.html?testName=ai-astar) 23:04:17 INFO - PROCESS | 10613 | RSS: Main: 267567104 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | __start_tp_report 23:04:17 INFO - PROCESS | 10613 | _x_x_mozilla_page_load 23:04:17 INFO - PROCESS | 10613 | _x_x_mozilla_page_load_details 23:04:17 INFO - PROCESS | 10613 | |i|pagename|runs| 23:04:17 INFO - PROCESS | 10613 | |0;ai-astar;114;108;114;114;112;113;114;113;114;112 23:04:17 INFO - PROCESS | 10613 | |1;audio-beat-detection;158;128;161;209;129;154;150;132;180;138 23:04:17 INFO - PROCESS | 10613 | |2;audio-dft;162;160;161;163;161;162;161;164;161;161 23:04:17 INFO - PROCESS | 10613 | |3;audio-fft;75;76;76;153;76;75;77;74;133;75 23:04:17 INFO - PROCESS | 10613 | |4;audio-oscillator;93;94;145;93;94;94;119;93;94;129 23:04:17 INFO - PROCESS | 10613 | |5;imaging-gaussian-blur;117;117;117;117;117;118;116;117;116;117 23:04:17 INFO - PROCESS | 10613 | |6;imaging-darkroom;146;145;147;146;145;146;146;150;145;146 23:04:17 INFO - PROCESS | 10613 | |7;imaging-desaturate;95;95;94;95;95;96;96;94;95;95 23:04:17 INFO - PROCESS | 10613 | |8;json-parse-financial;66;64;64;64;64;63;64;117;61;62 23:04:17 INFO - PROCESS | 10613 | |9;json-stringify-tinderbox;64;64;65;65;65;65;65;64;65;65 23:04:17 INFO - PROCESS | 10613 | |10;stanford-crypto-aes;82;81;83;81;84;82;82;89;84;82 23:04:17 INFO - PROCESS | 10613 | |11;stanford-crypto-ccm;130;132;135;127;130;132;126;131;207;130 23:04:17 INFO - PROCESS | 10613 | |12;stanford-crypto-pbkdf2;175;178;172;172;169;169;169;169;167;168 23:04:17 INFO - PROCESS | 10613 | |13;stanford-crypto-sha256-iterative;68;70;69;67;70;68;68;66;69;66 23:04:17 INFO - PROCESS | 10613 | __end_tp_report 23:04:17 INFO - PROCESS | 10613 | __start_cc_report 23:04:17 INFO - PROCESS | 10613 | _x_x_mozilla_cycle_collect,2121 23:04:17 INFO - PROCESS | 10613 | __end_cc_report 23:04:17 INFO - PROCESS | 10613 | __startTimestamp1463637857776__endTimestamp 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | ------- Summary: start ------- 23:04:17 INFO - PROCESS | 10613 | Number of tests: 14 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:1.87 (1.6%) stddev-sans-first:1.94 23:04:17 INFO - PROCESS | 10613 | Values: 114.0 108.0 114.0 114.0 112.0 113.0 114.0 113.0 114.0 112.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#1] audio-beat-detection Cycles:10 Average:153.90 Median:156.00 stddev:25.44 (16.3%) stddev-sans-first:26.94 23:04:17 INFO - PROCESS | 10613 | Values: 158.0 128.0 161.0 209.0 129.0 154.0 150.0 132.0 180.0 138.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 23:04:17 INFO - PROCESS | 10613 | Values: 162.0 160.0 161.0 163.0 161.0 162.0 161.0 164.0 161.0 161.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#3] audio-fft Cycles:10 Average:89.00 Median:75.50 stddev:28.86 (38.2%) stddev-sans-first:30.16 23:04:17 INFO - PROCESS | 10613 | Values: 75.0 76.0 76.0 153.0 76.0 75.0 77.0 74.0 133.0 75.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:93.50 stddev:19.11 (20.4%) stddev-sans-first:19.79 23:04:17 INFO - PROCESS | 10613 | Values: 93.0 94.0 145.0 93.0 94.0 94.0 119.0 93.0 94.0 129.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 23:04:17 INFO - PROCESS | 10613 | Values: 117.0 117.0 117.0 117.0 117.0 118.0 116.0 117.0 116.0 117.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.48 (1.0%) stddev-sans-first:1.56 23:04:17 INFO - PROCESS | 10613 | Values: 146.0 145.0 147.0 146.0 145.0 146.0 146.0 150.0 145.0 146.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 23:04:17 INFO - PROCESS | 10613 | Values: 95.0 95.0 94.0 95.0 95.0 96.0 96.0 94.0 95.0 95.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:64.00 stddev:16.95 (26.5%) stddev-sans-first:17.95 23:04:17 INFO - PROCESS | 10613 | Values: 66.0 64.0 64.0 64.0 64.0 63.0 64.0 117.0 61.0 62.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 23:04:17 INFO - PROCESS | 10613 | Values: 64.0 64.0 65.0 65.0 65.0 65.0 65.0 64.0 65.0 65.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.50 stddev:2.36 (2.9%) stddev-sans-first:2.47 23:04:17 INFO - PROCESS | 10613 | Values: 82.0 81.0 83.0 81.0 84.0 82.0 82.0 89.0 84.0 82.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:131.50 stddev:24.38 (18.5%) stddev-sans-first:25.68 23:04:17 INFO - PROCESS | 10613 | Values: 130.0 132.0 135.0 127.0 130.0 132.0 126.0 131.0 207.0 130.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:170.50 stddev:3.46 (2.0%) stddev-sans-first:3.32 23:04:17 INFO - PROCESS | 10613 | Values: 175.0 178.0 172.0 172.0 169.0 169.0 169.0 169.0 167.0 168.0 23:04:17 INFO - PROCESS | 10613 | 23:04:17 INFO - PROCESS | 10613 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.45 (2.1%) stddev-sans-first:1.54 23:04:17 INFO - PROCESS | 10613 | Values: 68.0 70.0 69.0 67.0 70.0 68.0 68.0 66.0 69.0 66.0 23:04:17 INFO - PROCESS | 10613 | -------- Summary: end -------- 23:04:17 INFO - PROCESS | 10613 | 23:04:18 INFO - TEST-INFO | 10613: exit 0 23:04:18 INFO - TEST-OK | kraken | took 65099ms 23:04:18 INFO - SUITE-END | took 1184s 23:04:18 INFO - Completed test suite (00:19:44) 23:04:18 INFO - javascript benchmark 23:04:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2691.219546331564, 2707.4179051503925, 2733.453309338133, 2737.931551711208, 2761.917142485725, 4676.976615116928, 4738.336632871396, 4743.928841067378, 4827.637927155464, 4867.099586576482, 4738.9052218955685, 4784.162771514988, 4786.282057691348, 4793.904121917568, 4800.9519904800945, 4311.956880431195, 4368.672349573779, 4422.579854913778, 4456.844010459635, 4541.318802179667, 4423.225935461298, 4444.599986001259, 4454.287314029756, 4463.330500424946, 4505.098980203956, 4313.611774940239, 4357.172137293904, 4376.343548467724, 4394.231009573312, 4405.713628614129, 4334.176506463762, 4364.869053928387, 4368.5194628590825, 4439.600435960779, 4452.109578084396, 4394.362817391492, 4401.823927042915, 4409.037978099747, 4439.445069366329, 4452.265376212921, 4561.429821272341, 4661.603763680057, 4759.095771803346, 4809.374781278447, 4812.614990800727, 5135.589152867761, 5163.199704045879, 5212.478752124795, 5214.634975551713, 5216.921746173811, 5136.409312929024, 5179.093658609766, 5239.187925871496, 5243.895122097575, 5246.8688282792855, 4677.95322046782, 4783.115123702126, 4813.15769740301, 4867.853964381039, 4880.707157570557, 5291.788328466895, 5294.285271488377, 5314.7608357624, 5366.946330536723, 5377.0, 5078.08594453002, 5084.669496482717, 5097.923531147036, 5148.6910785353, 5151.7166555839785, 4976.178930476503, 5025.3718285214345, 5080.74596270185, 5081.796728130834, 5171.612129090333, 5012.172991456368, 5029.346184995927, 5040.521150490697, 5046.1926091825135, 5094.77073531692, 5003.349564556657, 5014.222795466709, 5016.523430274118, 5025.14572522672, 5027.924581131286, 4194.8112334945, 4236.682248831288, 4273.572642735764, 4297.290946993711, 4309.987153019038, 3736.626337366242, 3742.2702572998005, 3742.62573742629, 3785.981070094632, 3819.1216020315483, 4473.664475164375, 4473.910521789546, 4483.1843103543115, 4512.323151527232, 4535.7732113394195, 4994.5754610858485, 5142.1258386073705, 5171.637985341065, 5186.325777648957, 5208.114620514521, 4494.348319493655, 4500.842470513517, 4511.70673906195, 4517.435320584927, 4558.6581006423985, 4636.79134438951, 4650.651201159926, 4653.022865198279, 4656.254999200111, 4696.38946936896, 4669.486356500782, 4723.197056500335, 4771.355866957916, 4793.017431426619, 4811.398575178103, 5076.873078172927, 5085.64400491962, 5096.210087436305, 5099.51554602327, 5147.356580427447, 4729.0, 4788.257820037899, 4802.711837289774, 4808.110499557593, 4809.639277054165, 4990.226514890198, 5019.648624596243, 5033.84898453047, 5054.545090941833, 5067.847964561069, 5124.052050370693, 5137.794488220429, 5147.40804807452, 5151.8196863109615, 5204.193350030751, 4871.707697538159, 4937.432195297587, 4978.701277923191, 4983.25251212321, 4988.451270360185, 4950.727709975986, 4995.550400463976, 5008.373953255843, 5010.899782004412, 5065.341505604395, 4929.334539837076, 4944.703317800943, 4969.776360063734, 4995.125852975787, 5010.423801262901, 4941.50584941503, 5018.0, 5050.444451110301, 5097.847064588068, 5105.617078719037, 4809.735464549343, 4857.0528746894015, 4867.075255701406, 4874.926876096933, 4908.533689299511], "value": 4706.436634133729, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.9668819870808, 594.5392320951262, 595.3689089565058, 601.2754630670041, 613.221209064488, 28271.29321766957, 28641.283967900814, 31030.37939241214, 31057.0, 41799.79100104503, 40396.19207615846, 42138.789306053506, 42954.57045429545, 42958.35562466565, 42965.0, 7888.645010974506, 8086.7465542840955, 8089.110197878229, 8092.312153466933, 8096.31181349583, 7478.476506644537, 7926.682932682715, 8066.677332906706, 10456.424896630682, 10702.250842441032, 10087.64693235737, 10470.21473389495, 10478.947605261963, 10504.527296271648, 10513.106385957202, 10045.698629041104, 10102.494875256209, 10105.141063009616, 10106.191504679608, 10108.747281317952, 10177.949110254476, 10280.177585793193, 10287.639932602398, 10325.380477171393, 10337.483125843753, 41209.0, 41330.38004429936, 41430.585694142974, 41592.0, 41637.79181104075, 3333.283344081034, 3338.2488939988307, 3350.2629421527226, 3365.2428203654226, 3380.4760262159402, 30537.694623053707, 31208.72141201917, 31213.687863121533, 31270.374592508022, 31490.0, 3542.6103128655827, 3566.2154326048226, 3601.837917293728, 3607.858801208294, 3610.711143108545, 287786.0, 289118.0, 289151.0, 289858.0, 290212.0, 63815.36184638187, 63818.68090659517, 64170.0, 64195.0, 64483.35516644867, 67290.66354668293, 67412.0, 67568.0, 67604.3239567598, 67625.0, 15701.371945122068, 16273.349066037226, 16286.592835178979, 16299.185040747916, 16309.83690163107, 12115.454804533818, 12152.331621760712, 12159.027277817753, 12164.2701437914, 12165.817512737316, 20000.499987500138, 20154.093065812074, 20270.885101319273, 20306.492337691678, 20346.796532034492, 7222.458315626349, 7311.341979221896, 7539.472236943361, 7568.810779730551, 7666.8849967250535, 839.5382539603194, 877.5743764274322, 1001.5142655811925, 1024.9077583017415, 1037.99481002596, 28448.0, 28830.135095947157, 28884.566731499046, 29182.562261566094, 29453.85273073621, 1010.1565193063727, 1044.4621020174616, 1052.2791887557048, 1121.9943900280446, 1124.5108377855659, 15152.0, 15270.16014119234, 15390.0, 15431.305591248421, 15559.688806223812, 394991.0, 759982.0, 782641.0, 784858.0, 786871.0, 54795.17807232895, 54989.450105498436, 55027.17459238115, 55285.44714552884, 55388.44611553833, 1144.4792619358338, 1245.769532636465, 1288.5425673885823, 1297.7923532234797, 1303.9413226404833, 27982.0, 30107.698923011365, 30111.397772044875, 30600.540991884693, 30625.23436914006, 42063.36904946367, 42179.1564168721, 42609.78695106505, 42634.0, 42665.36001959911, 329593.0, 332204.0, 332861.0, 333316.0, 334297.0, 22970.425739355982, 26302.736972630795, 42107.789461053726, 42795.786021069696, 42800.57199427966, 42534.0, 47339.0, 47416.52583474259, 48266.27600586064, 48413.5158648409, 39149.80425097857, 39222.2155556893, 39548.418063277146, 40476.1904761909, 40524.0, 38826.22347552977, 39467.605323947544, 40106.799466002485, 40193.79903100466, 40483.0, 39026.0, 39274.6072539271, 39420.80289598534, 40149.0, 40226.59773402228, 33609.49585756167, 35957.460638090975, 36346.404807152874, 36820.63179368172, 39524.20951580895, 27090.0, 27169.0, 27516.449671006867, 27529.31176720598, 27682.307942301602], "value": 19628.023834750355, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.84713267560295, 380.8895420328105, 386.19093000164645, 387.0710295291302, 397.67788091645787, 16402.671946561062, 17533.298668053296, 18239.361622343222, 18298.817011829877, 18324.175412106455, 15848.920755396206, 16866.325346986137, 16870.915645421785, 17596.0, 17778.28886844525, 5090.440051594331, 5155.097857874857, 5246.947530524703, 5271.604629652791, 5272.710000949965, 3675.7426980111404, 3860.3823388257883, 4026.516817981846, 5950.642961422307, 5957.404259574029, 5842.561807864406, 5887.411258874121, 5942.524598032169, 6006.579539432241, 6020.698965051752, 5925.851853703649, 5929.762809487637, 5981.0, 5993.071073983518, 6023.608465449754, 5374.113271310229, 5375.0, 5378.650387724785, 5383.111786555213, 5387.0303345397815, 22258.332250032527, 23555.646665300035, 23582.764172358227, 23624.29127126189, 24542.0, 2097.3078883968406, 2171.880546569938, 2182.5962196993605, 2488.228649118779, 2547.7070136934303, 13938.233397163152, 14488.348024338824, 14556.927215363856, 14983.475578354813, 15265.007774494623, 1737.9131043447776, 1839.5283772981695, 1963.0969753913216, 1966.7934866838918, 1978.8416926645978, 77676.61161694211, 81168.0, 81600.59199703964, 82764.0, 84756.15243847544, 16917.0, 16972.321107155574, 17006.829931700526, 17099.91450042767, 17164.313427462763, 29777.85111074474, 30045.399092018037, 30205.244868878013, 30355.848220759195, 30381.848090759406, 8690.217880390795, 8948.463092214488, 9025.232855207381, 9126.08739126082, 9275.768105797275, 7233.45749068822, 7494.063242094739, 7511.924880751122, 7576.469647124759, 7608.2011388804485, 8633.179847914438, 8651.134886511425, 8687.39188256816, 8815.471071735716, 8820.25027872638, 3130.3306898099745, 3392.9321413572084, 3408.5062810619315, 3421.469672200813, 3426.1605906553054, 818.3126174013738, 859.1494420523761, 877.6664867350366, 903.2545918746773, 913.8492148795507, 16812.915935420246, 16832.24254908532, 17081.231344589523, 17154.228059737343, 17304.307827687004, 896.0, 960.9183219426287, 975.7024107647094, 984.1831280037552, 992.374803873555, 9812.086643104669, 9884.406935583887, 10070.345427547187, 10073.546690398949, 10091.436495782284, 412435.0, 416813.0, 417052.0, 419926.0, 425131.0, 82503.0, 83319.0, 83407.0, 83796.58101709452, 86189.5690521556, 986.6694657289888, 1001.8697569316088, 1004.2367800471549, 1023.1763430438419, 1023.9692809215735, 15416.691666166838, 15544.611384715472, 15695.0, 15838.0, 16059.0, 26048.0, 26608.334791630365, 28356.0, 34287.31425371528, 36999.8150009259, 48855.75572122117, 49619.25571116364, 53485.465145349604, 53612.0, 54092.72953635207, 8110.635021423932, 8115.107338192676, 8118.107008228972, 8288.295494883003, 8501.404901656824, 9194.678186263449, 9438.622455101719, 11934.343611101473, 12018.399080046138, 12385.442655080724, 9984.35101718386, 10411.594434751514, 10457.163426925676, 10533.683989480327, 10598.099161571352, 10480.947595261974, 10491.318064325795, 10517.894821051997, 10603.628872989717, 10612.257141999986, 10618.0, 10629.681109566725, 10748.677539673823, 10781.730456738644, 10843.837342440027, 7757.806054848674, 7848.175941526058, 7956.801079973047, 8233.382496312921, 8250.834983300421, 8142.389320801082, 9760.902390976, 10630.574777008833, 10641.095506881693, 10907.509162087883], "value": 9297.209194305442, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1276.1959965221909, 1475.5425817996422, 1495.5214331413945, 1659.1787065402627, 1831.1576674729624, 6997.0, 7893.684252629895, 8134.064582572999, 8140.348772098233, 8284.088750237484, 8046.476978996361, 8226.753197404072, 8228.2594566489, 8229.135940726228, 8230.46501977371, 2283.817294616435, 2311.861288322706, 2312.028947841902, 2313.1556981701724, 2314.0512389920136, 2256.6953461282765, 2304.28567144185, 2305.1931823861682, 2306.1582522379376, 2308.90764369425, 2244.4052326133587, 2258.627326491135, 2266.433391652087, 2303.9308820735405, 2314.4908120213527, 2235.10595761695, 2252.4594097416584, 2255.751867294596, 2259.7062381890332, 2259.7853203945597, 2193.7586865444787, 2197.110170380998, 2198.428408613756, 2201.0535469748, 2202.713647225866, 7891.447598668038, 8130.674773009073, 8130.674773009073, 8190.7952301192945, 8216.424850260484, 4678.883027924295, 4785.66500344976, 4795.640326975491, 4799.520047995207, 4803.3035209894715, 3114.7196752292402, 3121.953170702441, 3176.5552822604855, 3179.173414912116, 3215.565898603705, 1022.7443139215196, 1036.3471013261596, 1036.4244851543908, 1042.525650828871, 1049.5906596427399, 10255.69232923006, 10605.575776968912, 10609.73475663107, 10911.727206819814, 10924.945375273073, 4211.305134652809, 4257.318828987347, 4326.242907491176, 4362.585554372329, 4371.256886329332, 8723.956380218058, 8741.388102832869, 8749.03760586335, 8770.429922055046, 8783.121687831293, 4820.301056346881, 4825.517448255217, 4826.831060912851, 4829.1307564638, 4832.855014349575, 3831.6934645228316, 4295.140971805651, 4433.7118087324225, 4468.553144685506, 4468.888277793016, 3532.523109380252, 3557.395242808677, 3564.055525285801, 3628.582712987987, 3655.908602284964, 1067.7864427114453, 1071.5178169823455, 1077.5743581285349, 1087.3258579680648, 1090.0, 672.5023482622823, 755.1693137548652, 876.2858270509514, 877.723517092114, 880.8062226310202, 4968.22992436173, 5254.211868219798, 5338.866528336823, 5344.8663783404945, 5372.489613486712, 913.0823522359985, 918.2699753695828, 922.4787994782927, 935.3078721745995, 945.0360632155164, 3717.4423836424753, 3857.324968130566, 3876.8061596920606, 3903.2583809076186, 3929.980350098231, 9282.72151835446, 9420.811583768424, 9689.70930872075, 9706.272029597809, 9713.417194968324, 9660.37207581519, 9721.56252968604, 9739.123478886933, 9742.171915387271, 9749.317547771589, 7357.153927298322, 7367.557946523226, 7388.704451821867, 7467.962660186773, 7507.361874240642, 5298.682079075268, 5328.7602057906715, 5354.3574771027725, 5368.785248590013, 5380.677159370293, 9169.266458683423, 9332.20676242527, 9410.811783764424, 9426.057394260793, 9431.198348140484, 9403.529823508934, 9434.339596228197, 9507.572159252712, 9568.76078098053, 9604.423734575948, 6857.7256909723055, 7176.246494118042, 7178.533395329287, 7255.2019277878335, 7270.563766174047, 7602.885956710543, 7729.4202934779, 7737.651805668871, 7746.380289576708, 7752.7674169775, 6668.199816022108, 6774.932250677627, 6960.547564408297, 7043.894341584983, 7297.635118244173, 6951.756688515878, 6980.7207711693, 7009.5230489728365, 7280.599567023864, 7311.707531698672, 6277.874442511033, 6320.6839658016, 6333.081703153109, 6341.651209183539, 6345.936540634534, 4549.886252843706, 4798.1123492154065, 4812.109759694467, 4839.008003359232, 4858.611311095033, 4911.803527858846, 4920.852374428773, 4962.255661650781, 4966.627502937222, 5047.570956468741], "value": 4271.273016213277, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.7938322557435, 301.20782342439395, 304.45958423797754, 317.29402080371165, 321.85516517567083, 4653.581177694007, 4769.976150119244, 4838.830640927568, 4877.073555945404, 4894.853154405369, 4603.941093548486, 4737.928931066037, 4744.193487107192, 4756.54812792785, 4762.809297675582, 3767.8492860285623, 3790.564085130204, 3794.715396345285, 3795.2030073684423, 3838.4626152338697, 3561.6972557332656, 3616.4575313702894, 3734.906627334311, 3817.9045523861846, 3828.549728173957, 3706.666400023997, 3734.3112958669712, 3743.4010558310674, 3743.4571987061995, 3754.81225938702, 3719.6280371962857, 3751.930699750558, 3752.043228976621, 3756.69946404287, 3760.931487681764, 3320.8671653133847, 3331.700146986783, 3339.2653616204398, 3351.245969656832, 3369.8989030328885, 4499.325101234808, 4571.3600095986585, 4575.084983003379, 4620.445546534404, 4665.930011049837, 4265.402843601898, 4270.145970805851, 4295.591918767712, 4299.72051816634, 4302.397664326997, 4848.418189817209, 4983.551480366785, 4988.925166122474, 5011.1481048221885, 5028.723420211887, 3629.782213067198, 3635.618669771304, 3638.872639457606, 3640.799756013418, 3642.1258897864586, 5279.498447647467, 5310.389305229909, 5313.362396512404, 5314.627976041678, 5320.6275560710765, 3735.402335626287, 3741.120836603396, 3741.831617577161, 3742.288965096623, 3749.981250093732, 3553.756185335112, 3760.8119594020736, 3774.2640185163627, 3774.339490589136, 3786.053486628343, 4267.231898258343, 4270.316749320094, 4271.252530807096, 4275.593818587229, 4278.572142785759, 4779.95220047802, 4992.425871024806, 5002.274670172877, 5022.296878437021, 5046.924296135561, 1213.9028877689764, 1260.7289432771997, 1262.8484581850237, 1273.7579859826603, 1274.8972139099797, 811.4076723991401, 813.353384059674, 821.954792486419, 836.3351135847016, 841.2975165736556, 3968.4245784361106, 3986.86045988389, 4021.5174179098694, 4046.4537287465814, 4048.4129801178665, 4538.977305113453, 4597.839075632337, 4606.447226332862, 4607.424071991001, 4619.491855895849, 4030.1386015928433, 4041.333180025263, 4042.1915616876154, 4042.413849991735, 4061.3501839705505, 2275.613145765224, 2422.9636555451684, 2432.1122790181457, 2444.633305004228, 2447.9388015299405, 4346.391505189276, 4384.276594361894, 4392.956070439254, 4403.889902752393, 4409.941614012678, 4646.465656449484, 4687.6249900007915, 4702.905941881211, 4705.270683044134, 4758.762061896891, 4075.694322925792, 4267.274563324242, 4316.223079845595, 4326.978365108154, 4360.869173924787, 4551.40831691878, 4575.794089265924, 4591.816327346869, 4605.171069207575, 4621.237495813153, 4458.6878918475395, 4472.3515090311075, 4501.8970352263905, 4503.950702430321, 4512.3908272382805, 4180.7909604519045, 4310.655147588122, 4341.630961368192, 4348.695591308578, 4405.31717583775, 3336.2993771308297, 3345.2974875276464, 3346.4478361071124, 3356.56364672591, 3356.6139893912514, 4585.243434833348, 4609.861704148881, 4617.145828021827, 4624.514425985358, 4926.078829256665, 4790.9281360780315, 4942.5304596063315, 4946.678465899705, 4969.1800852860315, 4997.475265097112, 4970.353853998957, 4980.178270585313, 4986.850394488171, 4999.350344317518, 5015.473375295542, 4618.745968971739, 4940.481249468754, 4949.950500494949, 4968.428630707371, 4968.652194346506, 4992.550670439678, 5017.598592112695, 5029.145045342228, 5040.873978150576, 5064.392272927273, 4907.312976183266, 4921.237208232729, 4926.802927882844, 4937.703737775745, 4938.111139994836], "value": 3650.752013842774, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.7785203365007, 783.0759703549811, 791.3708601661679, 799.1209669363697, 812.9756107316782, 12201.084918631104, 12559.0, 12698.365081745898, 12744.681382965442, 12752.171108877916, 12108.878911210886, 12726.36368181592, 12744.872551274484, 12752.617421477371, 12777.872221277785, 853.5902766672001, 866.0473479172922, 873.5544872115236, 874.1215078845783, 874.2612492443875, 853.6116067189445, 856.7258477287271, 857.1985193843776, 857.2713193785295, 857.6012154348227, 904.2178515584005, 908.7092130518237, 909.4725059465494, 910.0126362896266, 910.7632015675906, 906.8277027364812, 906.9501177435238, 907.0838453162321, 907.5008745190119, 907.6505545365015, 658.6607896933035, 658.7694306992562, 658.9967050164719, 659.0805825872881, 659.3867703036175, 11432.599859004895, 11433.0, 11467.942660286728, 11485.138614603939, 12200.511979520808, 10411.94794026025, 10505.474726263732, 10540.0, 10735.980081892209, 10822.296550724177, 9097.954510227406, 9158.358914875962, 9221.308401869886, 9359.672411465566, 9485.573149208301, 3252.4633435483115, 3270.1824543864036, 3303.537504749337, 3306.206510437502, 3395.898123056312, 44978.325325120146, 45242.77378613119, 45301.09397812058, 45698.31452528215, 45757.62727118192, 7863.292303692695, 8083.636236369378, 8213.712520061768, 8362.372822038371, 8405.747827565183, 15789.684206316038, 15816.446424375092, 15822.446214382442, 15827.366905323657, 15842.60393490172, 6341.714622841983, 6582.670866456658, 6722.697478613474, 6753.797386078426, 6953.895691564529, 3583.7670551414076, 3634.34581775283, 3664.0656632558525, 3665.70674346057, 3803.953912674037, 2828.250513613894, 2938.103878317091, 2940.2355387599378, 2941.808782429135, 2944.27865173034, 486.0667518364748, 486.3191531855431, 495.8760309922519, 512.9717865517432, 514.7452011254453, 607.1651479216077, 635.1171871099176, 636.2778246689948, 638.664701031962, 641.5348872067714, 7675.424343174284, 8099.230573095547, 8144.267015968591, 8156.0, 8275.022817158475, 623.2529656371258, 625.2621906150786, 629.2700467457727, 636.8694417644372, 642.3159335307976, 3511.2801875615437, 3643.690286325639, 3770.1517158639085, 3771.641694039062, 3779.0, 53455.19817202745, 54224.45775542273, 54518.0, 54747.72626136924, 54845.72577137089, 45883.5411645886, 48246.27630585544, 48671.78320542015, 48857.26714099292, 49696.751516242184, 41662.583374166476, 43712.3443148353, 44091.338629920574, 45650.08699825985, 46366.30450543245, 8281.627326770189, 8357.247847693738, 8416.831663366576, 8485.618147183268, 8547.786305342415, 30943.226419339695, 31392.372152556363, 31415.0, 31997.520037198996, 32178.35643287168, 37658.62341376551, 40960.79519602383, 40966.590334096276, 41183.176336472505, 42278.154436911704, 10149.086582207638, 10163.288569800043, 10175.643852465128, 10243.129334006693, 10251.384916904712, 11346.319220846775, 11493.942530287295, 11506.94246528762, 11643.53425862956, 12208.206466579644, 11142.665720028088, 11297.152713546353, 11315.547378104784, 11498.0, 11921.34432606215, 10985.285956412808, 11039.337639741641, 11439.714007149887, 11457.59898403552, 11569.363684997406, 10889.945550272198, 11361.715957101138, 11387.886121138683, 11630.825537616773, 11684.415779210836, 8986.820263594822, 9141.177294043302, 9164.266858651159, 9196.770080748034, 9631.518424078908, 7966.203379661987, 8255.25702686761, 8391.832163356821, 8441.788955276168, 8597.398182127441], "value": 5959.442525686787, "unit": "score"}], "name": "dromaeo_css", "value": 6561.765399143983, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 114.0, 114.0, 112.0, 113.0, 114.0, 113.0, 114.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 128.0, 161.0, 209.0, 129.0, 154.0, 150.0, 132.0, 180.0, 138.0], "value": 153.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 163.0, 161.0, 162.0, 161.0, 164.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 153.0, 76.0, 75.0, 77.0, 74.0, 133.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 145.0, 93.0, 94.0, 94.0, 119.0, 93.0, 94.0, 129.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 147.0, 146.0, 145.0, 146.0, 146.0, 150.0, 145.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 96.0, 94.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 64.0, 63.0, 64.0, 117.0, 61.0, 62.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 81.0, 84.0, 82.0, 82.0, 89.0, 84.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 135.0, 127.0, 130.0, 132.0, 126.0, 131.0, 207.0, 130.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 172.0, 172.0, 169.0, 169.0, 169.0, 169.0, 167.0, 168.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 67.0, 70.0, 68.0, 68.0, 66.0, 69.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1573.6999999999998, "alertThreshold": 2.0}]} 23:04:19 INFO - Return code: 0 23:04:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:04:19 ERROR - # TBPL SUCCESS # 23:04:19 INFO - Running post-action listener: _resource_record_post_action 23:04:19 INFO - ##### 23:04:19 INFO - ##### Finished run-tests step (success) 23:04:19 INFO - ##### 23:04:19 INFO - Running post-run listener: _resource_record_post_run 23:04:19 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530432000; Read time: 12; Write time: 457124 23:04:19 INFO - TinderboxPrint: CPU usage
13.2% 23:04:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:04:19 INFO - TinderboxPrint: I/O write bytes / time
530,432,000 / 457,124 23:04:19 INFO - TinderboxPrint: CPU idle
8,318.6 (86.8%) 23:04:19 INFO - TinderboxPrint: CPU user
1,221.0 (12.7%) 23:04:19 INFO - TinderboxPrint: Swap in / out
0 / 0 23:04:19 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 225398784; Read time: 0; Write time: 247072 23:04:19 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293634048; Read time: 12; Write time: 208804 23:04:19 INFO - Running post-run listener: _upload_blobber_files 23:04:19 INFO - Blob upload gear active. 23:04:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:04:19 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 23:04:19 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:04:19 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:04:20 INFO - (blobuploader) - INFO - Open directory for files ... 23:04:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:04:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:04:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:21 INFO - (blobuploader) - INFO - Done attempting. 23:04:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:04:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:04:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:25 INFO - (blobuploader) - INFO - Done attempting. 23:04:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:04:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:04:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:04:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:04:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:04:25 INFO - (blobuploader) - INFO - Done attempting. 23:04:25 INFO - (blobuploader) - INFO - Iteration through files over. 23:04:25 INFO - Return code: 0 23:04:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:04:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:04:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f2ad417650cbf8f9cb92962b411dc7e07ea3d5101d76e91ae064b23d272266ff11f93f4b73e8816fc5c171f042ec5b0434c4d2be0d76bc222caaff51ba400ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/48e6bee31d31b3fe9c01193e49fa89717abfea92243e35730c93a319d7c79f701163649da7b5af1f4878e6f885d18ad518dd5192d50da93bd8bc76ed81ae9bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2ab7b1807afc2f8e1c596da78b79faa904f795c8f79dc6d664766247aff6b9cc1604c317c69694be17026a5edd15715b9c5d7a6e82e112817baafc8ea39e399d"} 23:04:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:04:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:04:25 INFO - Contents: 23:04:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f2ad417650cbf8f9cb92962b411dc7e07ea3d5101d76e91ae064b23d272266ff11f93f4b73e8816fc5c171f042ec5b0434c4d2be0d76bc222caaff51ba400ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/48e6bee31d31b3fe9c01193e49fa89717abfea92243e35730c93a319d7c79f701163649da7b5af1f4878e6f885d18ad518dd5192d50da93bd8bc76ed81ae9bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2ab7b1807afc2f8e1c596da78b79faa904f795c8f79dc6d664766247aff6b9cc1604c317c69694be17026a5edd15715b9c5d7a6e82e112817baafc8ea39e399d"} 23:04:25 INFO - Running post-run listener: copy_logs_to_upload_dir 23:04:25 INFO - Copying logs to upload dir... 23:04:25 INFO - mkdir: /builds/slave/test/build/upload/logs 23:04:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.528931 ========= master_lag: 270.81 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 22 secs) (at 2016-05-18 23:08:56.519394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 mins, 51 secs) (at 2016-05-18 23:08:56.522893) ========= 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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f2ad417650cbf8f9cb92962b411dc7e07ea3d5101d76e91ae064b23d272266ff11f93f4b73e8816fc5c171f042ec5b0434c4d2be0d76bc222caaff51ba400ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/48e6bee31d31b3fe9c01193e49fa89717abfea92243e35730c93a319d7c79f701163649da7b5af1f4878e6f885d18ad518dd5192d50da93bd8bc76ed81ae9bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2ab7b1807afc2f8e1c596da78b79faa904f795c8f79dc6d664766247aff6b9cc1604c317c69694be17026a5edd15715b9c5d7a6e82e112817baafc8ea39e399d"} build_url:https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011404 build_url: 'https://queue.taskcluster.net/v1/task/R3DSw0anTuy6i25uAJ0t_A/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1f2ad417650cbf8f9cb92962b411dc7e07ea3d5101d76e91ae064b23d272266ff11f93f4b73e8816fc5c171f042ec5b0434c4d2be0d76bc222caaff51ba400ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/48e6bee31d31b3fe9c01193e49fa89717abfea92243e35730c93a319d7c79f701163649da7b5af1f4878e6f885d18ad518dd5192d50da93bd8bc76ed81ae9bcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2ab7b1807afc2f8e1c596da78b79faa904f795c8f79dc6d664766247aff6b9cc1604c317c69694be17026a5edd15715b9c5d7a6e82e112817baafc8ea39e399d"}' ========= master_lag: 171.34 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 mins, 51 secs) (at 2016-05-18 23:11:47.874436) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-05-18 23:11:47.874749) ========= 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=cbe409c6de289db6ac707de3000001d4-1463636335.916321-2053522413 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004726 ========= master_lag: 7.68 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 7 secs) (at 2016-05-18 23:11:55.563930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 23:11:55.564383) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 23:11:56.103919) ========= ========= Total master_lag: 449.91 =========