builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1463806352.84 results: success (0) buildid: 20160520212335 builduid: bde3aebb5da94d729c39cd9d97e4a407 revision: b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:32.836902) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:32.837369) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:32.837674) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:32.899696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:32.900130) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-20 21:52: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-20 21:52:33 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111085 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.047580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.047938) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022965 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.103139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.103486) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf --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 b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf --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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-20 21:52:33,355 truncating revision to first 12 chars 2016-05-20 21:52:33,355 Setting DEBUG logging. 2016-05-20 21:52:33,355 attempt 1/10 2016-05-20 21:52:33,355 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b7e3b5d47896?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 21:52:33,613 unpacking tar archive at: fx-team-b7e3b5d47896/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.708318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.708651) ========= script_repo_revision: b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.709200) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.709506) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 21:52:33.721961) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-20 21:52:33.722346) ========= /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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:52:34 INFO - MultiFileLogger online at 20160520 21:52:34 in /builds/slave/test 21:52: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 21:52:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:52:34 INFO - {'append_to_log': False, 21:52:34 INFO - 'base_work_dir': '/builds/slave/test', 21:52:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:52:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:52:34 INFO - 'branch': 'Fx-Team-Non-PGO', 21:52:34 INFO - 'buildbot_json_path': 'buildprops.json', 21:52:34 INFO - 'config_files': ('talos/linux_config.py',), 21:52:34 INFO - 'default_actions': ('clobber', 21:52:34 INFO - 'read-buildbot-config', 21:52:34 INFO - 'download-and-extract', 21:52:34 INFO - 'populate-webroot', 21:52:34 INFO - 'create-virtualenv', 21:52:34 INFO - 'install', 21:52:34 INFO - 'run-tests'), 21:52:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:52:34 INFO - 'download_minidump_stackwalk': True, 21:52:34 INFO - 'download_symbols': 'ondemand', 21:52:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:52:34 INFO - 'tooltool.py': '/tools/tooltool.py', 21:52:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:52:34 INFO - '/tools/misc-python/virtualenv.py')}, 21:52:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:52:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:52:34 INFO - 'installer_path': 'installer.exe', 21:52:34 INFO - 'log_level': 'info', 21:52:34 INFO - 'log_name': 'talos', 21:52:34 INFO - 'log_to_console': True, 21:52:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:52:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:52:34 INFO - 'opt_config_files': (), 21:52:34 INFO - 'pip_index': False, 21:52:34 INFO - 'sps_profile': False, 21:52:34 INFO - 'sps_profile_interval': 0, 21:52:34 INFO - 'suite': 'dromaeojs', 21:52:34 INFO - 'system_bits': '32', 21:52:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:52:34 INFO - 'title': 'talos-linux64-ix-004', 21:52:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:52:34 INFO - 'use_talos_json': True, 21:52:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:52:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:52:34 INFO - 'work_dir': 'build'} 21:52:34 INFO - ##### 21:52:34 INFO - ##### Running clobber step. 21:52:34 INFO - ##### 21:52:34 INFO - Running pre-action listener: _resource_record_pre_action 21:52:34 INFO - Running main action method: clobber 21:52:34 INFO - rmtree: /builds/slave/test/build 21:52:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:52:35 INFO - Running post-action listener: _resource_record_post_action 21:52:35 INFO - ##### 21:52:35 INFO - ##### Finished clobber step (success) 21:52:35 INFO - ##### 21:52:35 INFO - ##### 21:52:35 INFO - ##### Running read-buildbot-config step. 21:52:35 INFO - ##### 21:52:35 INFO - Running pre-action listener: _resource_record_pre_action 21:52:35 INFO - Running main action method: read_buildbot_config 21:52:35 INFO - Using buildbot properties: 21:52:35 INFO - { 21:52:35 INFO - "project": "", 21:52:35 INFO - "product": "firefox", 21:52:35 INFO - "script_repo_revision": "production", 21:52:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:52:35 INFO - "repository": "", 21:52:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:52:35 INFO - "buildid": "20160520212335", 21:52:35 INFO - "pgo_build": "False", 21:52:35 INFO - "basedir": "/builds/slave/test", 21:52:35 INFO - "buildnumber": 1702, 21:52:35 INFO - "slavename": "talos-linux64-ix-004", 21:52:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:52:35 INFO - "platform": "ubuntu64_hw", 21:52:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:52:35 INFO - "branch": "fx-team", 21:52:35 INFO - "repo_path": "integration/fx-team", 21:52:35 INFO - "revision": "b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf", 21:52:35 INFO - "stage_platform": "linux64", 21:52:35 INFO - "builduid": "bde3aebb5da94d729c39cd9d97e4a407", 21:52:35 INFO - "slavebuilddir": "test" 21:52:35 INFO - } 21:52:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:52:35 INFO - Running post-action listener: _resource_record_post_action 21:52:35 INFO - ##### 21:52:35 INFO - ##### Finished read-buildbot-config step (success) 21:52:35 INFO - ##### 21:52:35 INFO - ##### 21:52:35 INFO - ##### Running download-and-extract step. 21:52:35 INFO - ##### 21:52:35 INFO - Running pre-action listener: _resource_record_pre_action 21:52:35 INFO - Running main action method: download_and_extract 21:52:35 INFO - mkdir: /builds/slave/test/build/tests 21:52: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')]} 21:52:35 INFO - https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:52:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:52:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:52:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:39 INFO - Downloaded 1361 bytes. 21:52:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:52:39 INFO - Using the following test package requirements: 21:52:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:52:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:52:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:52:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'jsshell-linux-x86_64.zip'], 21:52:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:52:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:52:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:52:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:52:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:52:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:52:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:39 INFO - https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:52:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:52:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:52:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:44 INFO - Downloaded 22814444 bytes. 21:52:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:52:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:52:45 INFO - Return code: 0 21:52:45 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 21:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:45 INFO - https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:52:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:52:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:52:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:47 INFO - Downloaded 22814444 bytes. 21:52: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'] 21:52: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 21:52:49 INFO - Return code: 0 21:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:49 INFO - https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:52:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:52:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:52:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:50 INFO - Downloaded 10840359 bytes. 21:52: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'] 21:52: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 21:52:50 INFO - Return code: 0 21:52: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')]} 21:52:50 INFO - https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:52:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:52:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:52:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K1hytd8PS0CZh5ykp34Evg/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 21:52:51 INFO - Downloaded 54812750 bytes. 21:52:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:52:51 INFO - mkdir: /builds/slave/test/properties 21:52:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:52:51 INFO - Writing to file /builds/slave/test/properties/build_url 21:52:51 INFO - Contents: 21:52:51 INFO - build_url:https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:52:52 INFO - Running post-action listener: _resource_record_post_action 21:52:52 INFO - Running post-action listener: set_extra_try_arguments 21:52:52 INFO - ##### 21:52:52 INFO - ##### Finished download-and-extract step (success) 21:52:52 INFO - ##### 21:52:52 INFO - ##### 21:52:52 INFO - ##### Running populate-webroot step. 21:52:52 INFO - ##### 21:52:52 INFO - Running pre-action listener: _resource_record_pre_action 21:52:52 INFO - Running main action method: populate_webroot 21:52:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:52:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:52:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:52:52 INFO - '--tppagecycles', 21:52:52 INFO - '7'], 21:52:52 INFO - 'tests': ['tsvgm']}}, 21:52:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:52:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:52:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:52:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:52:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:52:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['damp', 'tps']}, 21:52:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'tests': ['damp', 'tps']}, 21:52:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['dromaeo_dom']}, 21:52:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:52:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'other-e10s': {'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['a11yr', 21:52:52 INFO - 'ts_paint', 21:52:52 INFO - 'tpaint', 21:52:52 INFO - 'sessionrestore', 21:52:52 INFO - 'sessionrestore_no_auto_restore', 21:52:52 INFO - 'tabpaint']}, 21:52:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['tsvgx', 21:52:52 INFO - 'tsvgr_opacity', 21:52:52 INFO - 'tart', 21:52:52 INFO - 'tscrollx', 21:52:52 INFO - 'cart']}, 21:52:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:52:52 INFO - 'tsvgr_opacity', 21:52:52 INFO - 'tart', 21:52:52 INFO - 'tscrollx', 21:52:52 INFO - 'cart']}, 21:52:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'talos_options': ['--disable-e10s'], 21:52:52 INFO - 'tests': ['tp5o']}, 21:52:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'tests': ['tp5o']}, 21:52:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'talos_options': ['--disable-e10s', 21:52:52 INFO - '--xperf_path', 21:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:52 INFO - 'tests': ['tp5n']}, 21:52:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:52:52 INFO - 'talos_options': ['--xperf_path', 21:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:52:52 INFO - 'tests': ['tp5n']}}, 21:52:52 INFO - 'talos.zip': {'path': '', 21:52:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:52:52 INFO - Running post-action listener: _resource_record_post_action 21:52:52 INFO - ##### 21:52:52 INFO - ##### Finished populate-webroot step (success) 21:52:52 INFO - ##### 21:52:52 INFO - ##### 21:52:52 INFO - ##### Running create-virtualenv step. 21:52:52 INFO - ##### 21:52:52 INFO - Running pre-action listener: _resource_record_pre_action 21:52:52 INFO - Running main action method: create_virtualenv 21:52:52 INFO - Creating virtualenv /builds/slave/test/build/venv 21:52:52 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 21:52:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:52:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:52:52 INFO - Using real prefix '/usr' 21:52:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:52:53 INFO - Installing distribute.............................................................................................................................................................................................done. 21:52:55 INFO - Installing pip.................done. 21:52:55 INFO - Return code: 0 21:52:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:52: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 21:52: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 21:52:55 INFO - Using env: {'DISPLAY': ':0', 21:52:55 INFO - 'HOME': '/home/cltbld', 21:52:55 INFO - 'LANG': 'en_US.UTF-8', 21:52:55 INFO - 'LANGUAGE': 'en_US:en', 21:52:55 INFO - 'LOGNAME': 'cltbld', 21:52:55 INFO - 'MAIL': '/var/mail/cltbld', 21:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:55 INFO - 'MOZ_NO_REMOTE': '1', 21:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:55 INFO - 'NO_EM_RESTART': '1', 21:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:55 INFO - 'PWD': '/builds/slave/test', 21:52:55 INFO - 'SHELL': '/bin/bash', 21:52:55 INFO - 'SHLVL': '1', 21:52:55 INFO - 'TERM': 'linux', 21:52:55 INFO - 'TMOUT': '86400', 21:52:55 INFO - 'USER': 'cltbld', 21:52:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:55 INFO - '_': '/tools/buildbot/bin/python'} 21:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52: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 21:52:55 INFO - Cleaning up... 21:52:55 INFO - Return code: 0 21:52:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:52: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 21:52: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 21:52:55 INFO - Using env: {'DISPLAY': ':0', 21:52:55 INFO - 'HOME': '/home/cltbld', 21:52:55 INFO - 'LANG': 'en_US.UTF-8', 21:52:55 INFO - 'LANGUAGE': 'en_US:en', 21:52:55 INFO - 'LOGNAME': 'cltbld', 21:52:55 INFO - 'MAIL': '/var/mail/cltbld', 21:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:55 INFO - 'MOZ_NO_REMOTE': '1', 21:52:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:55 INFO - 'NO_EM_RESTART': '1', 21:52:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:55 INFO - 'PWD': '/builds/slave/test', 21:52:55 INFO - 'SHELL': '/bin/bash', 21:52:55 INFO - 'SHLVL': '1', 21:52:55 INFO - 'TERM': 'linux', 21:52:55 INFO - 'TMOUT': '86400', 21:52:55 INFO - 'USER': 'cltbld', 21:52:55 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:55 INFO - '_': '/tools/buildbot/bin/python'} 21:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:55 INFO - Downloading/unpacking psutil>=3.1.1 21:52:55 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 21:52:55 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 21:52:55 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 21:52:55 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 21:52:55 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 21:52:55 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 21:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:52:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:57 INFO - Installing collected packages: psutil 21:52:57 INFO - Running setup.py install for psutil 21:52:57 INFO - building 'psutil._psutil_linux' extension 21:52:57 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 21:52:57 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 21:52:57 INFO - building 'psutil._psutil_posix' extension 21:52:57 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 21:52:57 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 21:52:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:52:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:52:57 INFO - Successfully installed psutil 21:52:57 INFO - Cleaning up... 21:52:58 INFO - Return code: 0 21:52:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:52: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')]} 21:52:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:52:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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')]} 21:52:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:52:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:52: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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:52: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 21:52: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 21:52:58 INFO - Using env: {'DISPLAY': ':0', 21:52:58 INFO - 'HOME': '/home/cltbld', 21:52:58 INFO - 'LANG': 'en_US.UTF-8', 21:52:58 INFO - 'LANGUAGE': 'en_US:en', 21:52:58 INFO - 'LOGNAME': 'cltbld', 21:52:58 INFO - 'MAIL': '/var/mail/cltbld', 21:52:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:52:58 INFO - 'MOZ_NO_REMOTE': '1', 21:52:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:52:58 INFO - 'NO_EM_RESTART': '1', 21:52:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:52:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:52:58 INFO - 'PWD': '/builds/slave/test', 21:52:58 INFO - 'SHELL': '/bin/bash', 21:52:58 INFO - 'SHLVL': '1', 21:52:58 INFO - 'TERM': 'linux', 21:52:58 INFO - 'TMOUT': '86400', 21:52:58 INFO - 'USER': 'cltbld', 21:52:58 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:52:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:52:58 INFO - '_': '/tools/buildbot/bin/python'} 21:52:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:52:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:52: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 21:52: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 21:52: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 21:52: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 21:52: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 21:52: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 21:53:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:53:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:53:00 INFO - Installing collected packages: mozsystemmonitor 21:53:00 INFO - Running setup.py install for mozsystemmonitor 21:53:00 INFO - Successfully installed mozsystemmonitor 21:53:00 INFO - Cleaning up... 21:53:00 INFO - Return code: 0 21:53:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:00 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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53:00 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 21:53:00 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 21:53:00 INFO - Using env: {'DISPLAY': ':0', 21:53:00 INFO - 'HOME': '/home/cltbld', 21:53:00 INFO - 'LANG': 'en_US.UTF-8', 21:53:00 INFO - 'LANGUAGE': 'en_US:en', 21:53:00 INFO - 'LOGNAME': 'cltbld', 21:53:00 INFO - 'MAIL': '/var/mail/cltbld', 21:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:00 INFO - 'MOZ_NO_REMOTE': '1', 21:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:00 INFO - 'NO_EM_RESTART': '1', 21:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:00 INFO - 'PWD': '/builds/slave/test', 21:53:00 INFO - 'SHELL': '/bin/bash', 21:53:00 INFO - 'SHLVL': '1', 21:53:00 INFO - 'TERM': 'linux', 21:53:00 INFO - 'TMOUT': '86400', 21:53:00 INFO - 'USER': 'cltbld', 21:53:00 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:00 INFO - '_': '/tools/buildbot/bin/python'} 21:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:02 INFO - Downloading blobuploader-1.2.4.tar.gz 21:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:53:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:53:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:03 INFO - Downloading docopt-0.6.1.tar.gz 21:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:53:03 INFO - Installing collected packages: blobuploader, requests, docopt 21:53:03 INFO - Running setup.py install for blobuploader 21:53:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:53:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:53:03 INFO - Running setup.py install for requests 21:53:03 INFO - Running setup.py install for docopt 21:53:03 INFO - Successfully installed blobuploader requests docopt 21:53:03 INFO - Cleaning up... 21:53:03 INFO - Return code: 0 21:53:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:03 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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53:03 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 21:53:03 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 21:53:03 INFO - Using env: {'DISPLAY': ':0', 21:53:03 INFO - 'HOME': '/home/cltbld', 21:53:03 INFO - 'LANG': 'en_US.UTF-8', 21:53:03 INFO - 'LANGUAGE': 'en_US:en', 21:53:03 INFO - 'LOGNAME': 'cltbld', 21:53:03 INFO - 'MAIL': '/var/mail/cltbld', 21:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:03 INFO - 'MOZ_NO_REMOTE': '1', 21:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:03 INFO - 'NO_EM_RESTART': '1', 21:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:03 INFO - 'PWD': '/builds/slave/test', 21:53:03 INFO - 'SHELL': '/bin/bash', 21:53:03 INFO - 'SHLVL': '1', 21:53:03 INFO - 'TERM': 'linux', 21:53:03 INFO - 'TMOUT': '86400', 21:53:03 INFO - 'USER': 'cltbld', 21:53:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:03 INFO - '_': '/tools/buildbot/bin/python'} 21:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:53:03 INFO - Running setup.py (path:/tmp/pip-rCdxkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:53:04 INFO - Running setup.py (path:/tmp/pip-4h7wRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:53:04 INFO - Running setup.py (path:/tmp/pip-xe9DkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:53:04 INFO - Running setup.py (path:/tmp/pip-gOIh81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:53:04 INFO - Running setup.py (path:/tmp/pip-rcfTgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:53:04 INFO - Running setup.py (path:/tmp/pip-DMKMmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:53:04 INFO - Running setup.py (path:/tmp/pip-8QiWH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:53:04 INFO - Running setup.py (path:/tmp/pip-gaILf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:53:04 INFO - Running setup.py (path:/tmp/pip-jLAt2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:53:04 INFO - Running setup.py (path:/tmp/pip-uRLlvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:53:04 INFO - Running setup.py (path:/tmp/pip-EIcHom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:53:04 INFO - Running setup.py (path:/tmp/pip-2tYM9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:53:04 INFO - Running setup.py (path:/tmp/pip-QxjkS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:53:04 INFO - Running setup.py (path:/tmp/pip-vl4Ncc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:53:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:53:04 INFO - Running setup.py (path:/tmp/pip-tOSudc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:53:05 INFO - Running setup.py (path:/tmp/pip-2moMyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:53:05 INFO - Running setup.py (path:/tmp/pip-q7ZZLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:53:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:53:05 INFO - Running setup.py install for manifestparser 21:53:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Running setup.py install for mozcrash 21:53:05 INFO - Running setup.py install for mozdebug 21:53:05 INFO - Running setup.py install for mozdevice 21:53:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Running setup.py install for mozfile 21:53:05 INFO - Running setup.py install for mozhttpd 21:53:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Running setup.py install for mozinfo 21:53:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Running setup.py install for mozInstall 21:53:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:53:05 INFO - Running setup.py install for mozleak 21:53:06 INFO - Running setup.py install for mozlog 21:53:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Running setup.py install for moznetwork 21:53:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Running setup.py install for mozprocess 21:53:06 INFO - Running setup.py install for mozprofile 21:53:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Running setup.py install for mozrunner 21:53:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Running setup.py install for mozscreenshot 21:53:06 INFO - Running setup.py install for moztest 21:53:06 INFO - Running setup.py install for mozversion 21:53:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:53:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:53:06 INFO - Cleaning up... 21:53:06 INFO - Return code: 0 21:53:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:53:06 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')]} 21:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:06 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')]} 21:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:06 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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53:06 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 21:53:06 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 21:53:06 INFO - Using env: {'DISPLAY': ':0', 21:53:06 INFO - 'HOME': '/home/cltbld', 21:53:06 INFO - 'LANG': 'en_US.UTF-8', 21:53:06 INFO - 'LANGUAGE': 'en_US:en', 21:53:06 INFO - 'LOGNAME': 'cltbld', 21:53:06 INFO - 'MAIL': '/var/mail/cltbld', 21:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:06 INFO - 'MOZ_NO_REMOTE': '1', 21:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:06 INFO - 'NO_EM_RESTART': '1', 21:53:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:06 INFO - 'PWD': '/builds/slave/test', 21:53:06 INFO - 'SHELL': '/bin/bash', 21:53:06 INFO - 'SHLVL': '1', 21:53:06 INFO - 'TERM': 'linux', 21:53:06 INFO - 'TMOUT': '86400', 21:53:06 INFO - 'USER': 'cltbld', 21:53:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:06 INFO - '_': '/tools/buildbot/bin/python'} 21:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:53:07 INFO - Running setup.py (path:/tmp/pip-QWV5B9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:53:07 INFO - Running setup.py (path:/tmp/pip-hrq6Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:53:07 INFO - Running setup.py (path:/tmp/pip-N9YZ81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:53:07 INFO - Running setup.py (path:/tmp/pip-oglbN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:53:07 INFO - Running setup.py (path:/tmp/pip-thYjhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:53:07 INFO - Running setup.py (path:/tmp/pip-Mpky1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:53:07 INFO - Running setup.py (path:/tmp/pip-IkHnNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:53:07 INFO - Running setup.py (path:/tmp/pip-zXsS7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:53:07 INFO - Running setup.py (path:/tmp/pip-EE9I4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:53:07 INFO - Running setup.py (path:/tmp/pip-1tGEJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:53:07 INFO - Running setup.py (path:/tmp/pip-47DTmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:53:07 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)) 21:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:53:08 INFO - Running setup.py (path:/tmp/pip-VmH3PP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:53: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)) 21:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:53:08 INFO - Running setup.py (path:/tmp/pip-QUiVPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:53: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)) 21:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:53:08 INFO - Running setup.py (path:/tmp/pip-XjgtbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:53:08 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)) 21:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:53:08 INFO - Running setup.py (path:/tmp/pip-f_K3KE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:53:08 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)) 21:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:53:08 INFO - Running setup.py (path:/tmp/pip-xAUjTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:53:08 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)) 21:53:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:53:08 INFO - Running setup.py (path:/tmp/pip-AQ9vUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:53:08 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)) 21:53:08 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)) 21:53:08 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)) 21:53:08 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)) 21:53:08 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)) 21:53:08 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)) 21:53:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:53:08 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 21:53:08 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 21:53:08 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 21:53:08 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 21:53:08 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 21:53:08 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 21:53:10 INFO - Downloading blessings-1.6.tar.gz 21:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:53:10 INFO - Installing collected packages: blessings 21:53:10 INFO - Running setup.py install for blessings 21:53:10 INFO - Successfully installed blessings 21:53:10 INFO - Cleaning up... 21:53:10 INFO - Return code: 0 21:53:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:53:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:53:10 INFO - Reading from file tmpfile_stdout 21:53:10 INFO - Current package versions: 21:53:10 INFO - argparse == 1.2.1 21:53:10 INFO - blessings == 1.6 21:53:10 INFO - blobuploader == 1.2.4 21:53:10 INFO - docopt == 0.6.1 21:53:10 INFO - manifestparser == 1.1 21:53:10 INFO - mozInstall == 1.12 21:53:10 INFO - mozcrash == 0.17 21:53:10 INFO - mozdebug == 0.1 21:53:10 INFO - mozdevice == 0.48 21:53:10 INFO - mozfile == 1.2 21:53:10 INFO - mozhttpd == 0.7 21:53:10 INFO - mozinfo == 0.9 21:53:10 INFO - mozleak == 0.1 21:53:10 INFO - mozlog == 3.2 21:53:10 INFO - moznetwork == 0.27 21:53:10 INFO - mozprocess == 0.22 21:53:10 INFO - mozprofile == 0.28 21:53:10 INFO - mozrunner == 6.11 21:53:10 INFO - mozscreenshot == 0.1 21:53:10 INFO - mozsystemmonitor == 0.3 21:53:10 INFO - moztest == 0.7 21:53:10 INFO - mozversion == 1.4 21:53:10 INFO - psutil == 3.1.1 21:53:10 INFO - requests == 1.2.3 21:53:10 INFO - wsgiref == 0.1.2 21:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:10 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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53:10 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 21:53:10 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 21:53:10 INFO - Using env: {'DISPLAY': ':0', 21:53:10 INFO - 'HOME': '/home/cltbld', 21:53:10 INFO - 'LANG': 'en_US.UTF-8', 21:53:10 INFO - 'LANGUAGE': 'en_US:en', 21:53:10 INFO - 'LOGNAME': 'cltbld', 21:53:10 INFO - 'MAIL': '/var/mail/cltbld', 21:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:10 INFO - 'MOZ_NO_REMOTE': '1', 21:53:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:10 INFO - 'NO_EM_RESTART': '1', 21:53:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:10 INFO - 'PWD': '/builds/slave/test', 21:53:10 INFO - 'SHELL': '/bin/bash', 21:53:10 INFO - 'SHLVL': '1', 21:53:10 INFO - 'TERM': 'linux', 21:53:10 INFO - 'TMOUT': '86400', 21:53:10 INFO - 'USER': 'cltbld', 21:53:10 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:10 INFO - '_': '/tools/buildbot/bin/python'} 21:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53: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)) 21:53:11 INFO - Cleaning up... 21:53:11 INFO - Return code: 0 21:53:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:53: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')]} 21:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53: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')]} 21:53:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53: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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53: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 21:53: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 21:53:11 INFO - Using env: {'DISPLAY': ':0', 21:53:11 INFO - 'HOME': '/home/cltbld', 21:53:11 INFO - 'LANG': 'en_US.UTF-8', 21:53:11 INFO - 'LANGUAGE': 'en_US:en', 21:53:11 INFO - 'LOGNAME': 'cltbld', 21:53:11 INFO - 'MAIL': '/var/mail/cltbld', 21:53:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:11 INFO - 'MOZ_NO_REMOTE': '1', 21:53:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:11 INFO - 'NO_EM_RESTART': '1', 21:53:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:11 INFO - 'PWD': '/builds/slave/test', 21:53:11 INFO - 'SHELL': '/bin/bash', 21:53:11 INFO - 'SHLVL': '1', 21:53:11 INFO - 'TERM': 'linux', 21:53:11 INFO - 'TMOUT': '86400', 21:53:11 INFO - 'USER': 'cltbld', 21:53:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:11 INFO - '_': '/tools/buildbot/bin/python'} 21:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:11 INFO - Downloading/unpacking jsonschema 21:53:11 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 21:53:11 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 21:53:11 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 21:53:11 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 21:53:11 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 21:53:11 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 21:53:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:53:13 INFO - Downloading/unpacking functools32 (from jsonschema) 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53:13 INFO - Downloading functools32-3.2.3-2.tar.gz 21:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:53:13 INFO - warning: no files found matching '*.txt' 21:53:13 INFO - no previously-included directories found matching 'build' 21:53:13 INFO - no previously-included directories found matching 'dist' 21:53:13 INFO - no previously-included directories found matching '.git*' 21:53:13 INFO - Installing collected packages: jsonschema, functools32 21:53:13 INFO - Running setup.py install for functools32 21:53:13 INFO - warning: no files found matching '*.txt' 21:53:13 INFO - no previously-included directories found matching 'build' 21:53:13 INFO - no previously-included directories found matching 'dist' 21:53:13 INFO - no previously-included directories found matching '.git*' 21:53:13 INFO - Successfully installed jsonschema functools32 21:53:13 INFO - Cleaning up... 21:53:13 INFO - Return code: 0 21:53:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:53:13 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')]} 21:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:13 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')]} 21:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:53:13 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 0x7f326c7d8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12ed770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, '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': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', '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 21:53:13 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 21:53:13 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 21:53:13 INFO - Using env: {'DISPLAY': ':0', 21:53:13 INFO - 'HOME': '/home/cltbld', 21:53:13 INFO - 'LANG': 'en_US.UTF-8', 21:53:13 INFO - 'LANGUAGE': 'en_US:en', 21:53:13 INFO - 'LOGNAME': 'cltbld', 21:53:13 INFO - 'MAIL': '/var/mail/cltbld', 21:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:13 INFO - 'MOZ_NO_REMOTE': '1', 21:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:13 INFO - 'NO_EM_RESTART': '1', 21:53:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:13 INFO - 'PWD': '/builds/slave/test', 21:53:13 INFO - 'SHELL': '/bin/bash', 21:53:13 INFO - 'SHLVL': '1', 21:53:13 INFO - 'TERM': 'linux', 21:53:13 INFO - 'TMOUT': '86400', 21:53:13 INFO - 'USER': 'cltbld', 21:53:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:13 INFO - '_': '/tools/buildbot/bin/python'} 21:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:53:14 INFO - Downloading/unpacking flake8 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:53:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:53:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:53:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53: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 21:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:53:16 INFO - no previously-included directories found matching 'docs/_build' 21:53:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:53:16 INFO - Running setup.py install for pep8 21:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:53:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:53:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:53:16 INFO - no previously-included directories found matching 'docs/_build' 21:53:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:53:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:53:16 INFO - Cleaning up... 21:53:16 INFO - Return code: 0 21:53:16 INFO - Running post-action listener: _resource_record_post_action 21:53:16 INFO - Running post-action listener: _start_resource_monitoring 21:53:16 INFO - Starting resource monitoring. 21:53:16 INFO - ##### 21:53:16 INFO - ##### Finished create-virtualenv step (success) 21:53:16 INFO - ##### 21:53:16 INFO - ##### 21:53:16 INFO - ##### Running install step. 21:53:16 INFO - ##### 21:53:16 INFO - Running pre-action listener: _resource_record_pre_action 21:53:16 INFO - Running main action method: install 21:53:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:53:17 INFO - Reading from file tmpfile_stdout 21:53:17 INFO - Detecting whether we're running mozinstall >=1.0... 21:53:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:53:17 INFO - Reading from file tmpfile_stdout 21:53:17 INFO - Output received: 21:53:17 INFO - Usage: mozinstall [options] installer 21:53:17 INFO - Options: 21:53:17 INFO - -h, --help show this help message and exit 21:53:17 INFO - -d DEST, --destination=DEST 21:53:17 INFO - Directory to install application into. [default: 21:53:17 INFO - "/builds/slave/test"] 21:53:17 INFO - --app=APP Application being installed. [default: firefox] 21:53:17 INFO - mkdir: /builds/slave/test/build/application 21:53: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'] 21:53: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 21:53:33 INFO - Reading from file tmpfile_stdout 21:53:33 INFO - Output received: 21:53:33 INFO - /builds/slave/test/build/application/firefox/firefox 21:53:33 INFO - Running post-action listener: _resource_record_post_action 21:53:33 INFO - ##### 21:53:33 INFO - ##### Finished install step (success) 21:53:33 INFO - ##### 21:53:33 INFO - ##### 21:53:33 INFO - ##### Running run-tests step. 21:53:33 INFO - ##### 21:53:33 INFO - Running pre-action listener: _resource_record_pre_action 21:53:33 INFO - Running main action method: run_tests 21:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:53:33 INFO - Python 2.7.3 21:53:33 INFO - Return code: 0 21:53:33 INFO - grabbing minidump binary from tooltool 21:53: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')]} 21:53: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 0x137c9c0>, '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 0x137d8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x137dd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:53: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 21:53: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 21:53: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 21:53:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:53:33 INFO - Return code: 0 21:53:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:53:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:53:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:53:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:53:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:53:34 INFO - Return code: 0 21:53: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/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 21:53: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/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 21:53:34 INFO - Using env: {'DISPLAY': ':0', 21:53:34 INFO - 'HOME': '/home/cltbld', 21:53:34 INFO - 'LANG': 'en_US.UTF-8', 21:53:34 INFO - 'LANGUAGE': 'en_US:en', 21:53:34 INFO - 'LOGNAME': 'cltbld', 21:53:34 INFO - 'MAIL': '/var/mail/cltbld', 21:53:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:53:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:53:34 INFO - 'MOZ_NO_REMOTE': '1', 21:53:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:53:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:34 INFO - 'NO_EM_RESTART': '1', 21:53:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:34 INFO - 'PWD': '/builds/slave/test', 21:53:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:53:34 INFO - 'SHELL': '/bin/bash', 21:53:34 INFO - 'SHLVL': '1', 21:53:34 INFO - 'TERM': 'linux', 21:53:34 INFO - 'TMOUT': '86400', 21:53:34 INFO - 'USER': 'cltbld', 21:53:34 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773', 21:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:53:34 INFO - '_': '/tools/buildbot/bin/python'} 21:53: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/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 21:53:34 INFO - mozversion application_buildid: 20160520212335 21:53:34 INFO - mozversion application_changeset: b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf 21:53:34 INFO - mozversion application_display_name: Nightly 21:53:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:53:34 INFO - mozversion application_name: Firefox 21:53:34 INFO - mozversion application_remotingname: firefox 21:53:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:53:34 INFO - mozversion application_vendor: Mozilla 21:53:34 INFO - mozversion application_version: 49.0a1 21:53:34 INFO - mozversion platform_buildid: 20160520212335 21:53:34 INFO - mozversion platform_changeset: b7e3b5d4789622d10bd7a0689c9479fe6c5c7ccf 21:53:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:53:34 INFO - mozversion platform_version: 49.0a1 21:53:34 INFO - using testdate: 1463806414 21:53:34 INFO - actual date: 1463806414 21:53:34 INFO - starting webserver on 'localhost:49133' 21:53:34 INFO - SUITE-START | Running 2 tests 21:53:34 INFO - TEST-START | dromaeo_css 21:53:34 INFO - operating with platform_type : linux_ 21:53:34 INFO - Initialising browser for dromaeo_css test... 21:53:34 INFO - TEST-INFO | started process 24695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdFDMEG/profile http://localhost:49133/getInfo.html) 21:53:38 INFO - PROCESS | 24695 | __metrics Screen width/height:1600/1200 21:53:38 INFO - PROCESS | 24695 | colorDepth:24 21:53:38 INFO - PROCESS | 24695 | Browser inner width/height: 1024/697 21:53:38 INFO - PROCESS | 24695 | __metrics 21:53:39 ERROR - PROCESS | 24695 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:53:40 INFO - TEST-INFO | 24695: exit 0 21:53:40 INFO - Browser initialized. 21:53:40 INFO - Running cycle 1/1 for dromaeo_css test... 21:53:40 INFO - TEST-INFO | started process 24779 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdFDMEG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:53:52 INFO - PROCESS | 24779 | RSS: Main: 155152384 21:53:52 INFO - PROCESS | 24779 | 21:54:44 INFO - PROCESS | 24779 | 1463806484405 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] 21:56:55 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49133/tests/dromaeo/cssquery-ext.html) 21:56:57 INFO - PROCESS | 24779 | RSS: Main: 276824064 21:56:57 INFO - PROCESS | 24779 | 21:59:59 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-ext.html (next: http://localhost:49133/tests/dromaeo/cssquery-jquery.html) 22:00:00 INFO - PROCESS | 24779 | RSS: Main: 237010944 22:00:00 INFO - PROCESS | 24779 | 22:03:02 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49133/tests/dromaeo/cssquery-mootools.html) 22:03:03 INFO - PROCESS | 24779 | RSS: Main: 239431680 22:03:03 INFO - PROCESS | 24779 | 22:06:05 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49133/tests/dromaeo/cssquery-prototype.html) 22:06:05 INFO - PROCESS | 24779 | RSS: Main: 233799680 22:06:05 INFO - PROCESS | 24779 | 22:09:08 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49133/tests/dromaeo/cssquery-yui.html) 22:09:09 INFO - PROCESS | 24779 | RSS: Main: 207568896 22:09:09 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | Cycle 1(1): loaded http://localhost:49133/tests/dromaeo/cssquery-yui.html (next: http://localhost:49133/tests/dromaeo/cssquery-dojo.html) 22:12:12 INFO - PROCESS | 24779 | RSS: Main: 270827520 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | __start_tp_report 22:12:12 INFO - PROCESS | 24779 | _x_x_mozilla_page_load 22:12:12 INFO - PROCESS | 24779 | _x_x_mozilla_page_load_details 22:12:12 INFO - PROCESS | 24779 | |i|pagename|runs| 22:12:12 INFO - PROCESS | 24779 | |0;dojo.html;2644.3124787555234;2647.0735242665082;2687.099821559776;2690.2870739254095;2696.5281075811727;4617.6536759742985;4645.698029628072;4654.162250794856;4671.766411679415;4712.246040633499;4674.205385084535;4686.789094490748;4690;4709.717416954985;4713.387259656249;4271.252530807104;4312.719673221246;4348.260795664744;4374.4531933508315;4517.322401639747;4447.177272204636;4455.977720111395;4472;4509.481409637884;4530.569595888385;4264.957350426487;4296.162248361571;4323.437953066113;4349.369341445473;4368.060866913597;4312.417823593837;4316.56834316569;4330.805113769888;4425.137098265839;4440.4893437254805;4360.847370342054;4364.388985542027;4368.453943257093;4417.712848664859;4424.80088396023;4509.030558429953;4615.14619795339;4618.9376443418205;4654.488006319302;4714.2692882602905;5090.796368145269;5137.2037334213255;5145.434002259748;5151.5106064923775;5153.66501177426;5126.5898728101665;5169.327987361657;5171.172612382038;5193.792248310063;5196.896062078775;4632.652551058684;4722.433308003062;4754.23932170851;4765.547273009059;4810.42274927011;5163.173892177234;5165.096108181053;5186.559142472932;5242;5247.16045432729;4985.4017517897355;5021.246812978083;5025.39695236574;5027.773750181251;5030.471800460972;4913.017396520639;5009.148444764399;5067.721275329818;5071.290019397287;5082.364704411949;4980.277859710394;5010.924836127461;5016.046951079284;5017.297578339036;5060.7722652480725;4845.9030819383415;4996.400431948189;5005.624578156653;5006.724630145377;5006.886574647465;4169.60388763067;4241.851535196315;4292.399064131024;4293.763842988603;4342.392065110887;3733.6453036961443;3744.288585168864;3752.2870654575545;3792.58281589025;3795.772253664789;4441.400410944481;4453.376527286183;4455.353973673799;4500.369948207253;4504.594586487232;4946.1344264753525;5015.724135172601;5062.82280120194;5069.5690866276045;5072.416672082684;4349.717268377545;4361.040571074359;4383.627391671744;4392.560743925582;4412.183746006999;4445.755483448442;4535.523770004168;4540.341650460665;4543.114092751882;4571.47428045779;4785.904281914272;4840.394950631171;4844.781984810763;4854.126257273725;4858.902821943612;5074.289599456005;5105.1576489880235;5111.565516931102;5112.105381558287;5161.690298582097;4635.327877457682;4775.952240477551;4835.782389792397;4844.176489996638;4897.608191344755;4990.126727822544;5014.598832093351;5026.321446604661;5039.521245481674;5077.949220507895;5097.439281678938;5099.0821652101495;5109.412417572027;5111.923321150261;5160.509751573594;4801.039792041675;4927.753612319441;4954.628402869727;4958.206686930074;4970.27930950003;4978.576820970114;4999.900002000012;5002.949970500394;5005.299258103795;5036.672616280077;4943.456219815889;4944.678595891256;4978.377702787152;4981.800727970841;4988.201887697951;4758.690685105457;4815.662903596714;4903.509649035068;4943.728094954668;5058.873528161826;4723.6929599575915;4818.759062046954;4841.709497430165;4848.515148485264;4888.95111048885 22:12:12 INFO - PROCESS | 24779 | |1;ext.html;543.076959721792;584.3893131677397;593.881223755249;597.7130977130977;599.5772980049065;40246.1950760985;41187.794061029694;41192.79403602978;41264.174716505724;42870;38727.80636096823;42682.57317426825;42701.57298427023;42768.786156069174;42814.357784633175;7462.253774622522;7753.651085701143;7963.084245311806;7975;10121.645742399018;9690.75773105671;9831.164351030171;10144.442055686934;10149;10163.644272450465;10168.542415591317;10244.74388140295;10258;10261;10264.33281836682;10128.392296462236;10186.59253629854;10212.08091271789;10213.540390682438;10220.693379198636;10033.448160351178;10100.848487272697;10109.241806864515;10118.696439106838;10131.290809643328;39990.40014399786;40308.798456007535;40394.39408408876;40397.19205615901;40408.39387409191;3038.4102798719023;3066.9386612267854;3094.1645755646073;3108.3317086826214;3114.0813460029344;30338.84830575855;30524.389512209855;30746;30984.0704778857;31992.20019499508;3501.5798104227656;3519.4896739972814;3574.6246644102257;3684.8157592120288;3706.499622550948;268290;268547;268813;269634;271385;58598.121028184614;58600;58658;58895.58283128782;59009.40990590125;61776.69111654503;61829.381706182365;61982.070268946;62061;62070.68964655148;12944.482220711254;13112.737745245042;13114.868851311556;13117.540886068942;13126.949844012455;8682.913170868336;9482.668106616235;9700.660476883275;9703.854442183372;9859.950700246452;19488.70766938497;19616.70574941377;19682.114304856317;19704.802951970585;19742.90128549348;6976.186670928491;7028.718851245892;7035.472339574552;7062.646867656596;7084.92915070853;818.7257268814894;882.8896387951506;908.8318661047728;1005.6731562242301;1048.8531605575226;27589.86205068962;28059.859700701367;28828;29134;29142.41715165685;930.1767935377239;936.7564433247405;952.0527075559723;964.4550828781715;984.6849008317413;14629.414823406945;15001.774973375408;15029.924850375897;15086.21971898022;15104.039776258545;390892;392246;392646;392662;393845;51252.231216531785;51328;51752.22371664428;51753.482465174864;51764;1113.799516087096;1166.3643314393846;1197.4850814149825;1206.957756478519;1209.2683926224684;25994.74005259923;29723.70276297296;29968;30278.54582181225;30324.090277291718;43833.34249986189;44436.33345499885;44484.55515444804;44494;44524.55475445204;302347;310512;311015;311254;311393;20935.476613084185;25520;25637.871810640827;26057.869710651325;26223.34441638898;43706.34440483332;43895.56104438915;44287.55712442963;44601;44613.553864462236;35321.293574127856;37464.438033430066;37764.81117594394;37953.2409351817;38233.617663823;36724.44913326356;36773.81613092025;37228.25543489169;37759.43360849535;37865.81067094757;36283.637163628024;36604.81697591495;36696.449553257255;36856.81571592125;37169.25661486809;31943.520847187774;32540.349193016482;33573.66426335705;33592;37217.81391093027;25470.981160754105;25591.314462970204;25829;26126.86936565305;26159.476810464064 22:12:12 INFO - PROCESS | 24779 | |2;jquery.html;362.51242079403204;366.98715544955917;368.96125906779787;381.4202412333253;382.02583412298634;15957.760633590506;17101.486955391327;17178.39875604354;17504.21231044603;17669.91165044173;15599.766003509956;15847.683046339094;16489.83510164898;16504.504864854072;16573.585660358498;5011.974940125303;5148.74256287186;5160.6903585784785;5208.973955130219;5218;3764.6047165047685;3955.802209889494;5079.746012699368;5633.324001119871;5950.077737950624;5994.190784244137;6003.879922401549;6018.368071352511;6027.517798576104;6033.9396606033815;5821.476067153934;5944.940550594503;6018.127371531104;6037.275526936752;6039.245094363205;3422.4524076147945;4889.217725163992;5229.843104706865;5242.7378631068295;5280.419153893069;20341.491462713402;20382;20479.69280460794;20541.178352865867;20969.266075687432;1887.5469887226966;1896.2888916656254;1969.5863868587614;2085.8331333493434;2552.1450314144786;12481.438335274934;13155.736885262337;13216.801747973788;13580.38888250031;13668.453261869514;1717.381742572673;1759.2523177649575;1776.2806063544285;1776.4315419065904;1807.6836553603198;58722.41277587212;67361;67743.9999999995;68177.31822681759;70201.2979870205;9882;10096.293259471913;10226.488675566192;10277.126444252173;10352.637657682095;14275;14329.57011289663;14344.713105738036;14404.783928241084;14406.567802965928;6736.797896063126;6752.97410776765;6880;6922.203946546111;6972.163340399185;6155.045967874987;6184.845378865564;6223.875522489616;6264.154339164245;6276.435120839146;4362.6946113772365;4460.264056430718;4499.977500112478;4523.796429160696;4578.58064000161;1720.5698575356162;1732.7400889866622;1810.8360509191389;1828.3509354179075;1829.231722676479;831.671489761553;835.6197929941862;843.9451435656786;850.9446885952393;915.4827522449928;16258.918705406397;16640.916795415946;16866.746998795028;17014;17297.827021729623;834.4492634860962;837.4472847920465;852.7953291210065;854.6325080215566;865.5326123893153;8954.552272386354;9254.629814807467;10307;10407.47962601882;10510.842337364946;385739;394606;409737;412560.9371953181;414994.1753203603;75642.62178689071;77057.61471192609;77178;77907.61046194733;80288;937.6530683647094;941.087145468894;957.1242313283268;959.4435227567993;962.1340793285958;15649.530514084596;15825.68348633044;15892.761608576111;16009.359625614845;16332.02007879531;34563.48154777731;35955.64044359523;37635;37800.43299350457;37911.2417751649;50738.49261507485;51018.74490627523;51385.74307128441;51615;52170.21744673757;7533.209013053551;7575.090989081125;7687.154413014676;7875.369970402468;8018.639161237642;8688.565571721516;10891.237613367306;11552.299731028234;11567.132465064986;11768.235064720766;9335.486548239913;9502.334836561375;9754.951225244113;9914;9999.250056245955;9732.70801875945;9861.852072219066;9909.950450247703;10190.235732319958;10250.179985600986;9957;10046;10103.292769506064;10148.847767283349;10392.532336044746;7494.250574942462;7528.134264559646;7537.773866784005;7656.081270247383;7713.7685869424;9337.253019758538;9677.854832177381;9820.214382849497;9898.653547125816;10400.583976641152 22:12:12 INFO - PROCESS | 24779 | |3;mootools.html;1278.8849003589676;1385.9792103118455;1408.3521580073168;1461.6784307452363;1699.762033315336;6792.524523283372;7419.109706835181;7721.227877212283;7728.265814747604;7954.602269886512;7615.77152685419;7745.651445684944;7760.7283745068935;7760.805979850506;7761.107472640634;1805.1515787579883;2044.815966563009;2104.189886893542;2190.967135492973;2221.0227499900075;2137.903794329251;2158;2159.902804373799;2163.2320526213202;2163.5240247145603;2132.2963422070757;2155.1056311630655;2156.4393257753018;2159.362987918568;2161.0383379396176;1792.354752289175;2038.4394291569788;2081.375587323804;2115.3125234298914;2118.2797848731507;2169.4684802223396;2192.8465007449486;2194.221051526701;2194.967075493869;2197.648376259807;7834.33408160307;7873.055233372032;7876;7876.251756083163;7883.054033515958;4230.17511585241;4390.143921935224;4391.165678521103;4391.890202744957;4404.6256068233915;2814.240155158096;2835.9858200708863;2994.0419065898923;3011.683773203804;3011.864466099031;892.3530440430636;911.9954400227956;913.6071489259616;914.6890057380523;937.9155875971195;10937.890621093768;10966.177536684781;10978.231523793338;10982.615608453745;10995.780084398348;4111.157212771374;4143.606357396043;4237.660987121085;4253.3194688849635;4274.337477690963;9194;9196.724098277062;9214.953925230466;9236.122568355995;9237.769055773659;4464.15181115587;4467.932981005288;4472.709273897186;4473.664475164311;4481.5294394088805;3410.5736782902136;3880.941785873214;3961.643452089326;3982.980085099556;4051.311277082844;3651.6165802590344;3687.170386663033;3703.185299234164;3787.450819631138;3798.088458769875;1176.6940595445076;1203.4404002139047;1206.6018213989362;1208.3656080557778;1212.4846940050443;777.9572123533147;787.4999375396665;792.3661071143063;798.4450806689296;824.4517395931758;5116.923246151311;5235.73821308933;5240.790368385222;5246.790128394821;5268.710220937808;405.0541984466245;413.5574934819714;414.69519902871366;416.10952561518377;417.39686153508495;3778.3765678663262;3962;4077.7553346799286;4092.4884389451317;4118.732282401635;9708.223342132613;9800.607975681036;10066.14437772783;10084.949575252076;10093.19254459656;9828.31201815866;9829.950850245847;9833.164181044549;9839.458829764431;9840.65557705477;6958.408535274457;7387.7783666490095;7561.962190189124;7577.825437057265;7589.316961473494;5347.7593508293;5352.9732351338;5367.5169234769055;5437.510624043855;5520.944790552204;8667.523286219319;8785.121487851164;9040.502772347583;9045;9047.547622618711;8634.136586341316;8954.238889694449;8959.955200224218;9230.446173229628;9245.768855778659;6141.447269745566;6634.237062737847;6824.692888819915;7144.214136444883;7234.6020968847215;7353.742619008309;7609.733659321897;7613.352865006536;7637.6563054661565;7702.614869256403;6844.555103918229;6877.105976223059;7056.823579410556;7141.143062832493;7156.033935418652;6646;6742.898856517058;6781.355771201926;6855.143107111611;6900;6167.427306036931;6225.719842607185;6273.309935906956;6341.8097457076365;6365.267994180728;4207.137536804887;4273.145370925889;4278.315469524861;4366.4978527468475;4492.528284530208;4387.583179597933;4412.646988240997;4426.557344265677;4517.299818528133;4588.174128656874 22:12:12 INFO - PROCESS | 24779 | |4;prototype.html;286.80927183423023;286.85657171414294;287.85319487061605;293.7165635162068;295.79737879552493;4756.904861902764;4774.594159496447;4810.206315957867;4811.542903424178;4822.5900798432085;4744.952550474494;4747.4540427850725;4748.453927798304;4754.811297175706;4767.377942131822;3572.356975744365;3690.7970061646597;3695.4641576971458;3801.6958643308467;3826.7512611680154;3791.867284645038;3806.3909774436097;3822.3310920588924;3832.846686132565;3841.135744457503;3582.588002379721;3759.342115129841;3792.4071889806346;3793.4499497572833;3807.9619203807883;3669;3749.306378320006;3749.7000239981007;3749.775013499199;3766.7174961877695;3324.2852786650747;3324.501324801275;3326.268220991378;3332.150301673058;3336.282699219679;4515.390422292981;4533.5745376223895;4539.4779600346055;4543.454785425737;4579.473360563545;4110.403991421257;4296.677749168824;4320;4362;4374.671899607542;4976.835076741629;4984.87537811554;4991.853502993711;5018.774155163026;5021.6735912165595;3738.513993180869;3745.7190710696805;3750.212455384345;3750.2312026034597;3751.981240093782;4995.625328100407;5231.398389185254;5250.553702935217;5263.184206447969;5294.814681486168;3810.161764411825;3815.294170578452;3821.541415030214;3834.539855217392;3839.0786211309087;3858.479105320746;3873.0511024799102;3879.8060096995036;3884.1066554692015;3885.611438856148;4280.80736366858;4286.249906266453;4303.505096913832;4366.6506679465565;4370.21336159488;4763.571278584944;4961.231009193581;4982.501749824988;5013.418089683816;5026.421961474477;1320.3002408723391;1323.7154011887299;1329.8470675872204;1332.780091284927;1333.3533236380347;849.4436144325476;855.8716192571164;855.9443636163629;866.1338661338661;894.1371576428777;4366.366876802909;4372.650187984954;4398.69209155356;4404.713693609905;4418.293073108287;5040.49595040493;5049.671771334852;5071.442141364447;5083.440821509631;5097.260897169889;4253.553791710771;4402.075564131568;4402.229609818333;4409.228385032607;4416.867493975186;2695.272276485377;2700.5814098814717;2717.266338088705;2719.225020869043;2720.9591856122174;4771.880702982384;4818.4217893852965;4829.420469543677;4830;4839.491853355347;4929.852104436873;5174.560162386164;5180.507851754077;5202.453742357074;5202.791888324425;4883.389576302962;4917.262410638433;4939.75301234944;4948.1835497142565;4950.455449900508;5143.434222235626;5151.407588127413;5196.766145523537;5197.740112994411;5215.8614967862995;4885.853424397274;5098.770555324945;5119.308893299357;5124.180131178993;5133.972081027899;4884.48712885142;4959.380077490314;4970.527799859007;4975.7014579123925;4979.551840334242;3652.634736526326;3661.139632186381;3679.6753168858654;3680.1167719747605;3695.7782533048103;4922.793915490728;5197.856471576247;5207.140821764367;5208.739563021758;5224.451432599522;5007.7245751484015;5008.2738002988635;5047.318611987334;5051.87370315745;5057;5028.723420211923;5046.596272298134;5058.721770302669;5059.380998080579;5060.493950605058;4839.758012099452;4990.575801056805;4990.975045124751;4994.200927851526;5007.24891266313;4863.179355973456;4954.779393394322;5050.520200580939;5053.69677819332;5115.25828754821;4906.509349065065;4911.140550403737;4922.704637721605;4949.232868905326;4949.232868905326 22:12:12 INFO - PROCESS | 24779 | |5;yui.html;591.4913174669784;713.7537549545405;715.7387553542957;728.2062551818517;745.4818900863902;12173.573924912629;13054.869451305483;13069.738605227902;13086.47654093835;13137.080404371674;12439.067069969744;12555.937220313908;12640.304783236941;12645.936770316159;12857.100002999794;644.0950464597248;813.4753084260649;818.1164342510104;828.0394742099166;828.5774255129897;783.6395258181243;819.1399031017407;820.1265652080544;823.6705317872838;825.2572684583893;858.7380848841101;860.6944534690189;861.1259571534897;862.3532350736947;863.6372723456163;655.577152736487;803.493798906685;850.345234169689;850.6512329944724;851.2126283188057;614.8985417406122;621.4748537485817;622.0296337713182;622.6108682073703;622.7944778223175;12990;13044.934775326063;13052.804207936793;13058.542950996669;13064.738705225842;12069.758604827943;12529.624111276677;12688.30214338211;12689.302088385135;12701.031340142294;11153.219274650777;11205.775884482264;11379.431028448544;11549.6535103947;11603.535858565647;4135.0063611644555;4160.126373461577;4197.538270790211;4246.639035681971;4277.165952639237;46685.53314466846;46705.5329446708;46806.531934680555;46839.29741053887;46848.76575617134;9346.766330841647;9622.51887405627;9822.214222862154;9947.45289009097;10043.548040338057;16450.34198632065;16475.505734827973;16480.670386592203;16494.505164845075;16496.917515412348;6765.560238584476;6779.694913728894;6811.727530898807;6876.587404755731;7002.509824312249;3785.299719551892;3821.6560509554274;3897.8830635080994;3940.0937784309767;3940.9802950985636;2748.051922086877;2850.7149285071328;2857.9997001049464;2858.3568697042997;2862.4990626640256;504.41235960106064;523.0506630465768;523.3484312031545;533.7598080863627;563.7829435667297;570.1277046119444;572.6678526454647;580.196427947296;592.5082181789105;601.2514419547691;6962.930370696329;7177.569345839266;7314.26857314275;7461.992630994855;8059.153788852203;469.1133757198897;576.495566379418;580.6574121268387;611.455804334143;618.5639124417276;3935.685145188378;4040.3939409088284;4059.6346328829954;4085.9182816343505;4163.83344666216;61448;62015.37984620096;62502;63060.68469657622;63484;25286.49427011486;25412.110576129748;25654.230373088838;25812;49379.25931111036;49407;50531;50756.23865642018;51146.48853511491;51604.22593661098;7181.461390395637;7237.469481903922;7362.558246505013;7466.701331946661;7993;32703.34593308168;33703.3259334807;34072.82963585166;34141.65858341484;34481.82759086188;41471;42610.36084458797;44643.19638446981;44777.77611112054;44851.32723009092;9939;9989.350692204984;10153.746156346151;10216.489175541343;10279.17766578657;10642;10908.61819836302;10938.507767150339;10989.395583242678;11348.432578371214;10187.847182292124;10193.23550733683;10242.59029638836;10378;10491.94754026225;9961.302708810314;10029.949850250994;10096.545655445376;10111.39331640104;10300.381977081399;9366.81266374655;9458;9778.608855645694;9784.608615655294;9832.55753491109;8781.648734050566;8824.602892869965;8909.821803564022;8960.507172105597;9183.586738596912;8709.91290087091;8759.16787905148;8971.551422428984;9027.954860225656;9073.228775554151 22:12:12 INFO - PROCESS | 24779 | __end_tp_report 22:12:12 INFO - PROCESS | 24779 | __start_cc_report 22:12:12 INFO - PROCESS | 24779 | _x_x_mozilla_cycle_collect,1110 22:12:12 INFO - PROCESS | 24779 | __end_cc_report 22:12:12 INFO - PROCESS | 24779 | __startTimestamp1463807532278__endTimestamp 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | ------- Summary: start ------- 22:12:12 INFO - PROCESS | 24779 | Number of tests: 6 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#0] dojo.html Cycles:165 Average:4691.01 Median:4818.76 stddev:489.39 (10.2%) stddev-sans-first:463.80 22:12:12 INFO - PROCESS | 24779 | Values: 2644.3 2647.1 2687.1 2690.3 2696.5 4617.7 4645.7 4654.2 4671.8 4712.2 4674.2 4686.8 4690.0 4709.7 4713.4 4271.3 4312.7 4348.3 4374.5 4517.3 4447.2 4456.0 4472.0 4509.5 4530.6 4265.0 4296.2 4323.4 4349.4 4368.1 4312.4 4316.6 4330.8 4425.1 4440.5 4360.8 4364.4 4368.5 4417.7 4424.8 4509.0 4615.1 4618.9 4654.5 4714.3 5090.8 5137.2 5145.4 5151.5 5153.7 5126.6 5169.3 5171.2 5193.8 5196.9 4632.7 4722.4 4754.2 4765.5 4810.4 5163.2 5165.1 5186.6 5242.0 5247.2 4985.4 5021.2 5025.4 5027.8 5030.5 4913.0 5009.1 5067.7 5071.3 5082.4 4980.3 5010.9 5016.0 5017.3 5060.8 4845.9 4996.4 5005.6 5006.7 5006.9 4169.6 4241.9 4292.4 4293.8 4342.4 3733.6 3744.3 3752.3 3792.6 3795.8 4441.4 4453.4 4455.4 4500.4 4504.6 4946.1 5015.7 5062.8 5069.6 5072.4 4349.7 4361.0 4383.6 4392.6 4412.2 4445.8 4535.5 4540.3 4543.1 4571.5 4785.9 4840.4 4844.8 4854.1 4858.9 5074.3 5105.2 5111.6 5112.1 5161.7 4635.3 4776.0 4835.8 4844.2 4897.6 4990.1 5014.6 5026.3 5039.5 5077.9 5097.4 5099.1 5109.4 5111.9 5160.5 4801.0 4927.8 4954.6 4958.2 4970.3 4978.6 4999.9 5002.9 5005.3 5036.7 4943.5 4944.7 4978.4 4981.8 4988.2 4758.7 4815.7 4903.5 4943.7 5058.9 4723.7 4818.8 4841.7 4848.5 4889.0 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#1] ext.html Cycles:180 Average:48983.75 Median:36650.63 stddev:86111.19 (235.0%) stddev-sans-first:86275.95 22:12:12 INFO - PROCESS | 24779 | Values: 543.1 584.4 593.9 597.7 599.6 40246.2 41187.8 41192.8 41264.2 42870.0 38727.8 42682.6 42701.6 42768.8 42814.4 7462.3 7753.7 7963.1 7975.0 10121.6 9690.8 9831.2 10144.4 10149.0 10163.6 10168.5 10244.7 10258.0 10261.0 10264.3 10128.4 10186.6 10212.1 10213.5 10220.7 10033.4 10100.8 10109.2 10118.7 10131.3 39990.4 40308.8 40394.4 40397.2 40408.4 3038.4 3066.9 3094.2 3108.3 3114.1 30338.8 30524.4 30746.0 30984.1 31992.2 3501.6 3519.5 3574.6 3684.8 3706.5 268290.0 268547.0 268813.0 269634.0 271385.0 58598.1 58600.0 58658.0 58895.6 59009.4 61776.7 61829.4 61982.1 62061.0 62070.7 12944.5 13112.7 13114.9 13117.5 13126.9 8682.9 9482.7 9700.7 9703.9 9860.0 19488.7 19616.7 19682.1 19704.8 19742.9 6976.2 7028.7 7035.5 7062.6 7084.9 818.7 882.9 908.8 1005.7 1048.9 27589.9 28059.9 28828.0 29134.0 29142.4 930.2 936.8 952.1 964.5 984.7 14629.4 15001.8 15029.9 15086.2 15104.0 390892.0 392246.0 392646.0 392662.0 393845.0 51252.2 51328.0 51752.2 51753.5 51764.0 1113.8 1166.4 1197.5 1207.0 1209.3 25994.7 29723.7 29968.0 30278.5 30324.1 43833.3 44436.3 44484.6 44494.0 44524.6 302347.0 310512.0 311015.0 311254.0 311393.0 20935.5 25520.0 25637.9 26057.9 26223.3 43706.3 43895.6 44287.6 44601.0 44613.6 35321.3 37464.4 37764.8 37953.2 38233.6 36724.4 36773.8 37228.3 37759.4 37865.8 36283.6 36604.8 36696.4 36856.8 37169.3 31943.5 32540.3 33573.7 33592.0 37217.8 25471.0 25591.3 25829.0 26126.9 26159.5 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#2] jquery.html Cycles:180 Average:24690.63 Median:4733.90 stddev:66530.45 (1405.4%) stddev-sans-first:66692.01 22:12:12 INFO - PROCESS | 24779 | Values: 362.5 367.0 369.0 381.4 382.0 15957.8 17101.5 17178.4 17504.2 17669.9 15599.8 15847.7 16489.8 16504.5 16573.6 5012.0 5148.7 5160.7 5209.0 5218.0 3764.6 3955.8 5079.7 5633.3 5950.1 5994.2 6003.9 6018.4 6027.5 6033.9 5821.5 5944.9 6018.1 6037.3 6039.2 3422.5 4889.2 5229.8 5242.7 5280.4 20341.5 20382.0 20479.7 20541.2 20969.3 1887.5 1896.3 1969.6 2085.8 2552.1 12481.4 13155.7 13216.8 13580.4 13668.5 1717.4 1759.3 1776.3 1776.4 1807.7 58722.4 67361.0 67744.0 68177.3 70201.3 9882.0 10096.3 10226.5 10277.1 10352.6 14275.0 14329.6 14344.7 14404.8 14406.6 6736.8 6753.0 6880.0 6922.2 6972.2 6155.0 6184.8 6223.9 6264.2 6276.4 4362.7 4460.3 4500.0 4523.8 4578.6 1720.6 1732.7 1810.8 1828.4 1829.2 831.7 835.6 843.9 850.9 915.5 16258.9 16640.9 16866.7 17014.0 17297.8 834.4 837.4 852.8 854.6 865.5 8954.6 9254.6 10307.0 10407.5 10510.8 385739.0 394606.0 409737.0 412560.9 414994.2 75642.6 77057.6 77178.0 77907.6 80288.0 937.7 941.1 957.1 959.4 962.1 15649.5 15825.7 15892.8 16009.4 16332.0 34563.5 35955.6 37635.0 37800.4 37911.2 50738.5 51018.7 51385.7 51615.0 52170.2 7533.2 7575.1 7687.2 7875.4 8018.6 8688.6 10891.2 11552.3 11567.1 11768.2 9335.5 9502.3 9755.0 9914.0 9999.3 9732.7 9861.9 9910.0 10190.2 10250.2 9957.0 10046.0 10103.3 10148.8 10392.5 7494.3 7528.1 7537.8 7656.1 7713.8 9337.3 9677.9 9820.2 9898.7 10400.6 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#3] mootools.html Cycles:180 Average:5145.43 Median:4504.91 stddev:2960.07 (65.7%) stddev-sans-first:2954.12 22:12:12 INFO - PROCESS | 24779 | Values: 1278.9 1386.0 1408.4 1461.7 1699.8 6792.5 7419.1 7721.2 7728.3 7954.6 7615.8 7745.7 7760.7 7760.8 7761.1 1805.2 2044.8 2104.2 2191.0 2221.0 2137.9 2158.0 2159.9 2163.2 2163.5 2132.3 2155.1 2156.4 2159.4 2161.0 1792.4 2038.4 2081.4 2115.3 2118.3 2169.5 2192.8 2194.2 2195.0 2197.6 7834.3 7873.1 7876.0 7876.3 7883.1 4230.2 4390.1 4391.2 4391.9 4404.6 2814.2 2836.0 2994.0 3011.7 3011.9 892.4 912.0 913.6 914.7 937.9 10937.9 10966.2 10978.2 10982.6 10995.8 4111.2 4143.6 4237.7 4253.3 4274.3 9194.0 9196.7 9215.0 9236.1 9237.8 4464.2 4467.9 4472.7 4473.7 4481.5 3410.6 3880.9 3961.6 3983.0 4051.3 3651.6 3687.2 3703.2 3787.5 3798.1 1176.7 1203.4 1206.6 1208.4 1212.5 778.0 787.5 792.4 798.4 824.5 5116.9 5235.7 5240.8 5246.8 5268.7 405.1 413.6 414.7 416.1 417.4 3778.4 3962.0 4077.8 4092.5 4118.7 9708.2 9800.6 10066.1 10084.9 10093.2 9828.3 9830.0 9833.2 9839.5 9840.7 6958.4 7387.8 7562.0 7577.8 7589.3 5347.8 5353.0 5367.5 5437.5 5520.9 8667.5 8785.1 9040.5 9045.0 9047.5 8634.1 8954.2 8960.0 9230.4 9245.8 6141.4 6634.2 6824.7 7144.2 7234.6 7353.7 7609.7 7613.4 7637.7 7702.6 6844.6 6877.1 7056.8 7141.1 7156.0 6646.0 6742.9 6781.4 6855.1 6900.0 6167.4 6225.7 6273.3 6341.8 6365.3 4207.1 4273.1 4278.3 4366.5 4492.5 4387.6 4412.6 4426.6 4517.3 4588.2 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#4] prototype.html Cycles:180 Average:4183.03 Median:4765.47 stddev:1196.73 (25.1%) stddev-sans-first:1163.81 22:12:12 INFO - PROCESS | 24779 | Values: 286.8 286.9 287.9 293.7 295.8 4756.9 4774.6 4810.2 4811.5 4822.6 4745.0 4747.5 4748.5 4754.8 4767.4 3572.4 3690.8 3695.5 3801.7 3826.8 3791.9 3806.4 3822.3 3832.8 3841.1 3582.6 3759.3 3792.4 3793.4 3808.0 3669.0 3749.3 3749.7 3749.8 3766.7 3324.3 3324.5 3326.3 3332.2 3336.3 4515.4 4533.6 4539.5 4543.5 4579.5 4110.4 4296.7 4320.0 4362.0 4374.7 4976.8 4984.9 4991.9 5018.8 5021.7 3738.5 3745.7 3750.2 3750.2 3752.0 4995.6 5231.4 5250.6 5263.2 5294.8 3810.2 3815.3 3821.5 3834.5 3839.1 3858.5 3873.1 3879.8 3884.1 3885.6 4280.8 4286.2 4303.5 4366.7 4370.2 4763.6 4961.2 4982.5 5013.4 5026.4 1320.3 1323.7 1329.8 1332.8 1333.4 849.4 855.9 855.9 866.1 894.1 4366.4 4372.7 4398.7 4404.7 4418.3 5040.5 5049.7 5071.4 5083.4 5097.3 4253.6 4402.1 4402.2 4409.2 4416.9 2695.3 2700.6 2717.3 2719.2 2721.0 4771.9 4818.4 4829.4 4830.0 4839.5 4929.9 5174.6 5180.5 5202.5 5202.8 4883.4 4917.3 4939.8 4948.2 4950.5 5143.4 5151.4 5196.8 5197.7 5215.9 4885.9 5098.8 5119.3 5124.2 5134.0 4884.5 4959.4 4970.5 4975.7 4979.6 3652.6 3661.1 3679.7 3680.1 3695.8 4922.8 5197.9 5207.1 5208.7 5224.5 5007.7 5008.3 5047.3 5051.9 5057.0 5028.7 5046.6 5058.7 5059.4 5060.5 4839.8 4990.6 4991.0 4994.2 5007.2 4863.2 4954.8 5050.5 5053.7 5115.3 4906.5 4911.1 4922.7 4949.2 4949.2 22:12:12 INFO - PROCESS | 24779 | 22:12:12 INFO - PROCESS | 24779 | [#5] yui.html Cycles:180 Average:13010.80 Median:25517.71 stddev:15575.98 (61.0%) stddev-sans-first:15591.75 22:12:12 INFO - PROCESS | 24779 | Values: 591.5 713.8 715.7 728.2 745.5 12173.6 13054.9 13069.7 13086.5 13137.1 12439.1 12555.9 12640.3 12645.9 12857.1 644.1 813.5 818.1 828.0 828.6 783.6 819.1 820.1 823.7 825.3 858.7 860.7 861.1 862.4 863.6 655.6 803.5 850.3 850.7 851.2 614.9 621.5 622.0 622.6 622.8 12990.0 13044.9 13052.8 13058.5 13064.7 12069.8 12529.6 12688.3 12689.3 12701.0 11153.2 11205.8 11379.4 11549.7 11603.5 4135.0 4160.1 4197.5 4246.6 4277.2 46685.5 46705.5 46806.5 46839.3 46848.8 9346.8 9622.5 9822.2 9947.5 10043.5 16450.3 16475.5 16480.7 16494.5 16496.9 6765.6 6779.7 6811.7 6876.6 7002.5 3785.3 3821.7 3897.9 3940.1 3941.0 2748.1 2850.7 2858.0 2858.4 2862.5 504.4 523.1 523.3 533.8 563.8 570.1 572.7 580.2 592.5 601.3 6962.9 7177.6 7314.3 7462.0 8059.2 469.1 576.5 580.7 611.5 618.6 3935.7 4040.4 4059.6 4085.9 4163.8 61448.0 62015.4 62502.0 63060.7 63484.0 25286.5 25412.1 25654.2 25812.0 49379.3 49407.0 50531.0 50756.2 51146.5 51604.2 7181.5 7237.5 7362.6 7466.7 7993.0 32703.3 33703.3 34072.8 34141.7 34481.8 41471.0 42610.4 44643.2 44777.8 44851.3 9939.0 9989.4 10153.7 10216.5 10279.2 10642.0 10908.6 10938.5 10989.4 11348.4 10187.8 10193.2 10242.6 10378.0 10491.9 9961.3 10029.9 10096.5 10111.4 10300.4 9366.8 9458.0 9778.6 9784.6 9832.6 8781.6 8824.6 8909.8 8960.5 9183.6 8709.9 8759.2 8971.6 9028.0 9073.2 22:12:12 INFO - PROCESS | 24779 | -------- Summary: end -------- 22:12:12 INFO - PROCESS | 24779 | 22:12:13 INFO - TEST-INFO | 24779: exit 0 22:12:13 INFO - TEST-OK | dromaeo_css | took 1118789ms 22:12:13 INFO - TEST-START | kraken 22:12:13 INFO - operating with platform_type : linux_ 22:12:13 INFO - Initialising browser for kraken test... 22:12:13 INFO - TEST-INFO | started process 24956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsKBUGb/profile http://localhost:49133/getInfo.html) 22:12:17 INFO - PROCESS | 24956 | __metrics Screen width/height:1600/1200 22:12:17 INFO - PROCESS | 24956 | colorDepth:24 22:12:17 INFO - PROCESS | 24956 | Browser inner width/height: 1024/697 22:12:17 INFO - PROCESS | 24956 | __metrics 22:12:18 ERROR - PROCESS | 24956 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:12:19 INFO - TEST-INFO | 24956: exit 0 22:12:19 INFO - Browser initialized. 22:12:19 INFO - Running cycle 1/1 for kraken test... 22:12:19 INFO - TEST-INFO | started process 25036 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsKBUGb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:31 INFO - PROCESS | 25036 | RSS: Main: 154898432 22:12:31 INFO - PROCESS | 25036 | 22:12:34 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49133/tests/kraken/driver.html?testName=audio-beat-detection) 22:12:35 INFO - PROCESS | 25036 | RSS: Main: 209907712 22:12:35 INFO - PROCESS | 25036 | 22:12:37 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49133/tests/kraken/driver.html?testName=audio-dft) 22:12:38 INFO - PROCESS | 25036 | RSS: Main: 197922816 22:12:38 INFO - PROCESS | 25036 | 22:12:41 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49133/tests/kraken/driver.html?testName=audio-fft) 22:12:42 INFO - PROCESS | 25036 | RSS: Main: 268079104 22:12:42 INFO - PROCESS | 25036 | 22:12:44 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49133/tests/kraken/driver.html?testName=audio-oscillator) 22:12:45 INFO - PROCESS | 25036 | RSS: Main: 178061312 22:12:45 INFO - PROCESS | 25036 | 22:12:47 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49133/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:12:48 INFO - PROCESS | 25036 | RSS: Main: 181788672 22:12:48 INFO - PROCESS | 25036 | 22:12:51 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49133/tests/kraken/driver.html?testName=imaging-darkroom) 22:12:52 INFO - PROCESS | 25036 | RSS: Main: 216576000 22:12:52 INFO - PROCESS | 25036 | 22:12:55 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49133/tests/kraken/driver.html?testName=imaging-desaturate) 22:12:56 INFO - PROCESS | 25036 | RSS: Main: 215134208 22:12:56 INFO - PROCESS | 25036 | 22:12:59 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49133/tests/kraken/driver.html?testName=json-parse-financial) 22:13:00 INFO - PROCESS | 25036 | RSS: Main: 215810048 22:13:00 INFO - PROCESS | 25036 | 22:13:02 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49133/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:13:02 INFO - PROCESS | 25036 | RSS: Main: 204328960 22:13:02 INFO - PROCESS | 25036 | 22:13:04 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:13:05 INFO - PROCESS | 25036 | RSS: Main: 207900672 22:13:05 INFO - PROCESS | 25036 | 22:13:07 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:13:08 INFO - PROCESS | 25036 | RSS: Main: 218673152 22:13:08 INFO - PROCESS | 25036 | 22:13:11 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:13:11 INFO - PROCESS | 25036 | RSS: Main: 225730560 22:13:11 INFO - PROCESS | 25036 | 22:13:14 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:13:15 INFO - PROCESS | 25036 | RSS: Main: 221847552 22:13:15 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | Cycle 1(1): loaded http://localhost:49133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49133/tests/kraken/driver.html?testName=ai-astar) 22:13:17 INFO - PROCESS | 25036 | RSS: Main: 268500992 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | __start_tp_report 22:13:17 INFO - PROCESS | 25036 | _x_x_mozilla_page_load 22:13:17 INFO - PROCESS | 25036 | _x_x_mozilla_page_load_details 22:13:17 INFO - PROCESS | 25036 | |i|pagename|runs| 22:13:17 INFO - PROCESS | 25036 | |0;ai-astar;114;110;116;113;115;114;114;115;116;114 22:13:17 INFO - PROCESS | 25036 | |1;audio-beat-detection;154;131;131;215;132;143;153;130;164;129 22:13:17 INFO - PROCESS | 25036 | |2;audio-dft;161;160;160;161;161;162;162;160;162;158 22:13:17 INFO - PROCESS | 25036 | |3;audio-fft;76;77;73;139;76;77;76;76;129;74 22:13:17 INFO - PROCESS | 25036 | |4;audio-oscillator;95;94;163;94;94;102;127;93;93;124 22:13:17 INFO - PROCESS | 25036 | |5;imaging-gaussian-blur;117;116;116;117;117;117;117;117;116;116 22:13:17 INFO - PROCESS | 25036 | |6;imaging-darkroom;147;146;145;146;146;146;147;146;146;145 22:13:17 INFO - PROCESS | 25036 | |7;imaging-desaturate;94;95;95;95;94;95;95;94;94;94 22:13:17 INFO - PROCESS | 25036 | |8;json-parse-financial;66;65;65;65;65;65;65;116;62;65 22:13:17 INFO - PROCESS | 25036 | |9;json-stringify-tinderbox;65;64;65;65;65;64;64;64;64;64 22:13:17 INFO - PROCESS | 25036 | |10;stanford-crypto-aes;83;83;94;85;83;83;83;82;90;90 22:13:17 INFO - PROCESS | 25036 | |11;stanford-crypto-ccm;137;144;130;130;141;128;128;137;196;142 22:13:17 INFO - PROCESS | 25036 | |12;stanford-crypto-pbkdf2;177;174;170;174;168;173;174;172;169;169 22:13:17 INFO - PROCESS | 25036 | |13;stanford-crypto-sha256-iterative;66;67;67;67;67;72;67;65;68;65 22:13:17 INFO - PROCESS | 25036 | __end_tp_report 22:13:17 INFO - PROCESS | 25036 | __start_cc_report 22:13:17 INFO - PROCESS | 25036 | _x_x_mozilla_cycle_collect,2147 22:13:17 INFO - PROCESS | 25036 | __end_cc_report 22:13:17 INFO - PROCESS | 25036 | __startTimestamp1463807597441__endTimestamp 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | ------- Summary: start ------- 22:13:17 INFO - PROCESS | 25036 | Number of tests: 14 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:1.73 (1.5%) stddev-sans-first:1.83 22:13:17 INFO - PROCESS | 25036 | Values: 114.0 110.0 116.0 113.0 115.0 114.0 114.0 115.0 116.0 114.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:148.00 stddev:26.56 (17.9%) stddev-sans-first:28.09 22:13:17 INFO - PROCESS | 25036 | Values: 154.0 131.0 131.0 215.0 132.0 143.0 153.0 130.0 164.0 129.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.32 22:13:17 INFO - PROCESS | 25036 | Values: 161.0 160.0 160.0 161.0 161.0 162.0 162.0 160.0 162.0 158.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#3] audio-fft Cycles:10 Average:87.30 Median:76.00 stddev:24.76 (32.6%) stddev-sans-first:25.92 22:13:17 INFO - PROCESS | 25036 | Values: 76.0 77.0 73.0 139.0 76.0 77.0 76.0 76.0 129.0 74.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#4] audio-oscillator Cycles:10 Average:107.90 Median:93.50 stddev:23.30 (24.9%) stddev-sans-first:24.24 22:13:17 INFO - PROCESS | 25036 | Values: 95.0 94.0 163.0 94.0 94.0 102.0 127.0 93.0 93.0 124.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:13:17 INFO - PROCESS | 25036 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 22:13:17 INFO - PROCESS | 25036 | Values: 147.0 146.0 145.0 146.0 146.0 146.0 147.0 146.0 146.0 145.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:13:17 INFO - PROCESS | 25036 | Values: 94.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 94.0 94.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#8] json-parse-financial Cycles:10 Average:69.90 Median:65.00 stddev:16.23 (25.0%) stddev-sans-first:17.15 22:13:17 INFO - PROCESS | 25036 | Values: 66.0 65.0 65.0 65.0 65.0 65.0 65.0 116.0 62.0 65.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 22:13:17 INFO - PROCESS | 25036 | Values: 65.0 64.0 65.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:84.00 stddev:4.17 (5.0%) stddev-sans-first:4.31 22:13:17 INFO - PROCESS | 25036 | Values: 83.0 83.0 94.0 85.0 83.0 83.0 83.0 82.0 90.0 90.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:139.00 stddev:20.13 (14.5%) stddev-sans-first:21.29 22:13:17 INFO - PROCESS | 25036 | Values: 137.0 144.0 130.0 130.0 141.0 128.0 128.0 137.0 196.0 142.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:173.50 stddev:2.91 (1.7%) stddev-sans-first:2.46 22:13:17 INFO - PROCESS | 25036 | Values: 177.0 174.0 170.0 174.0 168.0 173.0 174.0 172.0 169.0 169.0 22:13:17 INFO - PROCESS | 25036 | 22:13:17 INFO - PROCESS | 25036 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.97 (2.9%) stddev-sans-first:2.05 22:13:17 INFO - PROCESS | 25036 | Values: 66.0 67.0 67.0 67.0 67.0 72.0 67.0 65.0 68.0 65.0 22:13:17 INFO - PROCESS | 25036 | -------- Summary: end -------- 22:13:17 INFO - PROCESS | 25036 | 22:13:18 INFO - TEST-INFO | 25036: exit 0 22:13:18 INFO - TEST-OK | kraken | took 65429ms 22:13:18 INFO - SUITE-END | took 1184s 22:13:18 INFO - Completed test suite (00:19:44) 22:13:18 INFO - javascript benchmark 22:13:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2644.3124787555234, 2647.0735242665082, 2687.099821559776, 2690.2870739254095, 2696.5281075811727, 4617.6536759742985, 4645.698029628072, 4654.162250794856, 4671.766411679415, 4712.246040633499, 4674.205385084535, 4686.789094490748, 4690.0, 4709.717416954985, 4713.387259656249, 4271.252530807104, 4312.719673221246, 4348.260795664744, 4374.4531933508315, 4517.322401639747, 4447.177272204636, 4455.977720111395, 4472.0, 4509.481409637884, 4530.569595888385, 4264.957350426487, 4296.162248361571, 4323.437953066113, 4349.369341445473, 4368.060866913597, 4312.417823593837, 4316.56834316569, 4330.805113769888, 4425.137098265839, 4440.4893437254805, 4360.847370342054, 4364.388985542027, 4368.453943257093, 4417.712848664859, 4424.80088396023, 4509.030558429953, 4615.14619795339, 4618.9376443418205, 4654.488006319302, 4714.2692882602905, 5090.796368145269, 5137.2037334213255, 5145.434002259748, 5151.5106064923775, 5153.66501177426, 5126.5898728101665, 5169.327987361657, 5171.172612382038, 5193.792248310063, 5196.896062078775, 4632.652551058684, 4722.433308003062, 4754.23932170851, 4765.547273009059, 4810.42274927011, 5163.173892177234, 5165.096108181053, 5186.559142472932, 5242.0, 5247.16045432729, 4985.4017517897355, 5021.246812978083, 5025.39695236574, 5027.773750181251, 5030.471800460972, 4913.017396520639, 5009.148444764399, 5067.721275329818, 5071.290019397287, 5082.364704411949, 4980.277859710394, 5010.924836127461, 5016.046951079284, 5017.297578339036, 5060.7722652480725, 4845.9030819383415, 4996.400431948189, 5005.624578156653, 5006.724630145377, 5006.886574647465, 4169.60388763067, 4241.851535196315, 4292.399064131024, 4293.763842988603, 4342.392065110887, 3733.6453036961443, 3744.288585168864, 3752.2870654575545, 3792.58281589025, 3795.772253664789, 4441.400410944481, 4453.376527286183, 4455.353973673799, 4500.369948207253, 4504.594586487232, 4946.1344264753525, 5015.724135172601, 5062.82280120194, 5069.5690866276045, 5072.416672082684, 4349.717268377545, 4361.040571074359, 4383.627391671744, 4392.560743925582, 4412.183746006999, 4445.755483448442, 4535.523770004168, 4540.341650460665, 4543.114092751882, 4571.47428045779, 4785.904281914272, 4840.394950631171, 4844.781984810763, 4854.126257273725, 4858.902821943612, 5074.289599456005, 5105.1576489880235, 5111.565516931102, 5112.105381558287, 5161.690298582097, 4635.327877457682, 4775.952240477551, 4835.782389792397, 4844.176489996638, 4897.608191344755, 4990.126727822544, 5014.598832093351, 5026.321446604661, 5039.521245481674, 5077.949220507895, 5097.439281678938, 5099.0821652101495, 5109.412417572027, 5111.923321150261, 5160.509751573594, 4801.039792041675, 4927.753612319441, 4954.628402869727, 4958.206686930074, 4970.27930950003, 4978.576820970114, 4999.900002000012, 5002.949970500394, 5005.299258103795, 5036.672616280077, 4943.456219815889, 4944.678595891256, 4978.377702787152, 4981.800727970841, 4988.201887697951, 4758.690685105457, 4815.662903596714, 4903.509649035068, 4943.728094954668, 5058.873528161826, 4723.6929599575915, 4818.759062046954, 4841.709497430165, 4848.515148485264, 4888.95111048885], "value": 4660.1020806760625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.076959721792, 584.3893131677397, 593.881223755249, 597.7130977130977, 599.5772980049065, 40246.1950760985, 41187.794061029694, 41192.79403602978, 41264.174716505724, 42870.0, 38727.80636096823, 42682.57317426825, 42701.57298427023, 42768.786156069174, 42814.357784633175, 7462.253774622522, 7753.651085701143, 7963.084245311806, 7975.0, 10121.645742399018, 9690.75773105671, 9831.164351030171, 10144.442055686934, 10149.0, 10163.644272450465, 10168.542415591317, 10244.74388140295, 10258.0, 10261.0, 10264.33281836682, 10128.392296462236, 10186.59253629854, 10212.08091271789, 10213.540390682438, 10220.693379198636, 10033.448160351178, 10100.848487272697, 10109.241806864515, 10118.696439106838, 10131.290809643328, 39990.40014399786, 40308.798456007535, 40394.39408408876, 40397.19205615901, 40408.39387409191, 3038.4102798719023, 3066.9386612267854, 3094.1645755646073, 3108.3317086826214, 3114.0813460029344, 30338.84830575855, 30524.389512209855, 30746.0, 30984.0704778857, 31992.20019499508, 3501.5798104227656, 3519.4896739972814, 3574.6246644102257, 3684.8157592120288, 3706.499622550948, 268290.0, 268547.0, 268813.0, 269634.0, 271385.0, 58598.121028184614, 58600.0, 58658.0, 58895.58283128782, 59009.40990590125, 61776.69111654503, 61829.381706182365, 61982.070268946, 62061.0, 62070.68964655148, 12944.482220711254, 13112.737745245042, 13114.868851311556, 13117.540886068942, 13126.949844012455, 8682.913170868336, 9482.668106616235, 9700.660476883275, 9703.854442183372, 9859.950700246452, 19488.70766938497, 19616.70574941377, 19682.114304856317, 19704.802951970585, 19742.90128549348, 6976.186670928491, 7028.718851245892, 7035.472339574552, 7062.646867656596, 7084.92915070853, 818.7257268814894, 882.8896387951506, 908.8318661047728, 1005.6731562242301, 1048.8531605575226, 27589.86205068962, 28059.859700701367, 28828.0, 29134.0, 29142.41715165685, 930.1767935377239, 936.7564433247405, 952.0527075559723, 964.4550828781715, 984.6849008317413, 14629.414823406945, 15001.774973375408, 15029.924850375897, 15086.21971898022, 15104.039776258545, 390892.0, 392246.0, 392646.0, 392662.0, 393845.0, 51252.231216531785, 51328.0, 51752.22371664428, 51753.482465174864, 51764.0, 1113.799516087096, 1166.3643314393846, 1197.4850814149825, 1206.957756478519, 1209.2683926224684, 25994.74005259923, 29723.70276297296, 29968.0, 30278.54582181225, 30324.090277291718, 43833.34249986189, 44436.33345499885, 44484.55515444804, 44494.0, 44524.55475445204, 302347.0, 310512.0, 311015.0, 311254.0, 311393.0, 20935.476613084185, 25520.0, 25637.871810640827, 26057.869710651325, 26223.34441638898, 43706.34440483332, 43895.56104438915, 44287.55712442963, 44601.0, 44613.553864462236, 35321.293574127856, 37464.438033430066, 37764.81117594394, 37953.2409351817, 38233.617663823, 36724.44913326356, 36773.81613092025, 37228.25543489169, 37759.43360849535, 37865.81067094757, 36283.637163628024, 36604.81697591495, 36696.449553257255, 36856.81571592125, 37169.25661486809, 31943.520847187774, 32540.349193016482, 33573.66426335705, 33592.0, 37217.81391093027, 25470.981160754105, 25591.314462970204, 25829.0, 26126.86936565305, 26159.476810464064], "value": 18437.49111601442, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.51242079403204, 366.98715544955917, 368.96125906779787, 381.4202412333253, 382.02583412298634, 15957.760633590506, 17101.486955391327, 17178.39875604354, 17504.21231044603, 17669.91165044173, 15599.766003509956, 15847.683046339094, 16489.83510164898, 16504.504864854072, 16573.585660358498, 5011.974940125303, 5148.74256287186, 5160.6903585784785, 5208.973955130219, 5218.0, 3764.6047165047685, 3955.802209889494, 5079.746012699368, 5633.324001119871, 5950.077737950624, 5994.190784244137, 6003.879922401549, 6018.368071352511, 6027.517798576104, 6033.9396606033815, 5821.476067153934, 5944.940550594503, 6018.127371531104, 6037.275526936752, 6039.245094363205, 3422.4524076147945, 4889.217725163992, 5229.843104706865, 5242.7378631068295, 5280.419153893069, 20341.491462713402, 20382.0, 20479.69280460794, 20541.178352865867, 20969.266075687432, 1887.5469887226966, 1896.2888916656254, 1969.5863868587614, 2085.8331333493434, 2552.1450314144786, 12481.438335274934, 13155.736885262337, 13216.801747973788, 13580.38888250031, 13668.453261869514, 1717.381742572673, 1759.2523177649575, 1776.2806063544285, 1776.4315419065904, 1807.6836553603198, 58722.41277587212, 67361.0, 67743.9999999995, 68177.31822681759, 70201.2979870205, 9882.0, 10096.293259471913, 10226.488675566192, 10277.126444252173, 10352.637657682095, 14275.0, 14329.57011289663, 14344.713105738036, 14404.783928241084, 14406.567802965928, 6736.797896063126, 6752.97410776765, 6880.0, 6922.203946546111, 6972.163340399185, 6155.045967874987, 6184.845378865564, 6223.875522489616, 6264.154339164245, 6276.435120839146, 4362.6946113772365, 4460.264056430718, 4499.977500112478, 4523.796429160696, 4578.58064000161, 1720.5698575356162, 1732.7400889866622, 1810.8360509191389, 1828.3509354179075, 1829.231722676479, 831.671489761553, 835.6197929941862, 843.9451435656786, 850.9446885952393, 915.4827522449928, 16258.918705406397, 16640.916795415946, 16866.746998795028, 17014.0, 17297.827021729623, 834.4492634860962, 837.4472847920465, 852.7953291210065, 854.6325080215566, 865.5326123893153, 8954.552272386354, 9254.629814807467, 10307.0, 10407.47962601882, 10510.842337364946, 385739.0, 394606.0, 409737.0, 412560.9371953181, 414994.1753203603, 75642.62178689071, 77057.61471192609, 77178.0, 77907.61046194733, 80288.0, 937.6530683647094, 941.087145468894, 957.1242313283268, 959.4435227567993, 962.1340793285958, 15649.530514084596, 15825.68348633044, 15892.761608576111, 16009.359625614845, 16332.02007879531, 34563.48154777731, 35955.64044359523, 37635.0, 37800.43299350457, 37911.2417751649, 50738.49261507485, 51018.74490627523, 51385.74307128441, 51615.0, 52170.21744673757, 7533.209013053551, 7575.090989081125, 7687.154413014676, 7875.369970402468, 8018.639161237642, 8688.565571721516, 10891.237613367306, 11552.299731028234, 11567.132465064986, 11768.235064720766, 9335.486548239913, 9502.334836561375, 9754.951225244113, 9914.0, 9999.250056245955, 9732.70801875945, 9861.852072219066, 9909.950450247703, 10190.235732319958, 10250.179985600986, 9957.0, 10046.0, 10103.292769506064, 10148.847767283349, 10392.532336044746, 7494.250574942462, 7528.134264559646, 7537.773866784005, 7656.081270247383, 7713.7685869424, 9337.253019758538, 9677.854832177381, 9820.214382849497, 9898.653547125816, 10400.583976641152], "value": 8252.582681544138, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1278.8849003589676, 1385.9792103118455, 1408.3521580073168, 1461.6784307452363, 1699.762033315336, 6792.524523283372, 7419.109706835181, 7721.227877212283, 7728.265814747604, 7954.602269886512, 7615.77152685419, 7745.651445684944, 7760.7283745068935, 7760.805979850506, 7761.107472640634, 1805.1515787579883, 2044.815966563009, 2104.189886893542, 2190.967135492973, 2221.0227499900075, 2137.903794329251, 2158.0, 2159.902804373799, 2163.2320526213202, 2163.5240247145603, 2132.2963422070757, 2155.1056311630655, 2156.4393257753018, 2159.362987918568, 2161.0383379396176, 1792.354752289175, 2038.4394291569788, 2081.375587323804, 2115.3125234298914, 2118.2797848731507, 2169.4684802223396, 2192.8465007449486, 2194.221051526701, 2194.967075493869, 2197.648376259807, 7834.33408160307, 7873.055233372032, 7876.0, 7876.251756083163, 7883.054033515958, 4230.17511585241, 4390.143921935224, 4391.165678521103, 4391.890202744957, 4404.6256068233915, 2814.240155158096, 2835.9858200708863, 2994.0419065898923, 3011.683773203804, 3011.864466099031, 892.3530440430636, 911.9954400227956, 913.6071489259616, 914.6890057380523, 937.9155875971195, 10937.890621093768, 10966.177536684781, 10978.231523793338, 10982.615608453745, 10995.780084398348, 4111.157212771374, 4143.606357396043, 4237.660987121085, 4253.3194688849635, 4274.337477690963, 9194.0, 9196.724098277062, 9214.953925230466, 9236.122568355995, 9237.769055773659, 4464.15181115587, 4467.932981005288, 4472.709273897186, 4473.664475164311, 4481.5294394088805, 3410.5736782902136, 3880.941785873214, 3961.643452089326, 3982.980085099556, 4051.311277082844, 3651.6165802590344, 3687.170386663033, 3703.185299234164, 3787.450819631138, 3798.088458769875, 1176.6940595445076, 1203.4404002139047, 1206.6018213989362, 1208.3656080557778, 1212.4846940050443, 777.9572123533147, 787.4999375396665, 792.3661071143063, 798.4450806689296, 824.4517395931758, 5116.923246151311, 5235.73821308933, 5240.790368385222, 5246.790128394821, 5268.710220937808, 405.0541984466245, 413.5574934819714, 414.69519902871366, 416.10952561518377, 417.39686153508495, 3778.3765678663262, 3962.0, 4077.7553346799286, 4092.4884389451317, 4118.732282401635, 9708.223342132613, 9800.607975681036, 10066.14437772783, 10084.949575252076, 10093.19254459656, 9828.31201815866, 9829.950850245847, 9833.164181044549, 9839.458829764431, 9840.65557705477, 6958.408535274457, 7387.7783666490095, 7561.962190189124, 7577.825437057265, 7589.316961473494, 5347.7593508293, 5352.9732351338, 5367.5169234769055, 5437.510624043855, 5520.944790552204, 8667.523286219319, 8785.121487851164, 9040.502772347583, 9045.0, 9047.547622618711, 8634.136586341316, 8954.238889694449, 8959.955200224218, 9230.446173229628, 9245.768855778659, 6141.447269745566, 6634.237062737847, 6824.692888819915, 7144.214136444883, 7234.6020968847215, 7353.742619008309, 7609.733659321897, 7613.352865006536, 7637.6563054661565, 7702.614869256403, 6844.555103918229, 6877.105976223059, 7056.823579410556, 7141.143062832493, 7156.033935418652, 6646.0, 6742.898856517058, 6781.355771201926, 6855.143107111611, 6900.0, 6167.427306036931, 6225.719842607185, 6273.309935906956, 6341.8097457076365, 6365.267994180728, 4207.137536804887, 4273.145370925889, 4278.315469524861, 4366.4978527468475, 4492.528284530208, 4387.583179597933, 4412.646988240997, 4426.557344265677, 4517.299818528133, 4588.174128656874], "value": 4044.535034917515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.80927183423023, 286.85657171414294, 287.85319487061605, 293.7165635162068, 295.79737879552493, 4756.904861902764, 4774.594159496447, 4810.206315957867, 4811.542903424178, 4822.5900798432085, 4744.952550474494, 4747.4540427850725, 4748.453927798304, 4754.811297175706, 4767.377942131822, 3572.356975744365, 3690.7970061646597, 3695.4641576971458, 3801.6958643308467, 3826.7512611680154, 3791.867284645038, 3806.3909774436097, 3822.3310920588924, 3832.846686132565, 3841.135744457503, 3582.588002379721, 3759.342115129841, 3792.4071889806346, 3793.4499497572833, 3807.9619203807883, 3669.0, 3749.306378320006, 3749.7000239981007, 3749.775013499199, 3766.7174961877695, 3324.2852786650747, 3324.501324801275, 3326.268220991378, 3332.150301673058, 3336.282699219679, 4515.390422292981, 4533.5745376223895, 4539.4779600346055, 4543.454785425737, 4579.473360563545, 4110.403991421257, 4296.677749168824, 4320.0, 4362.0, 4374.671899607542, 4976.835076741629, 4984.87537811554, 4991.853502993711, 5018.774155163026, 5021.6735912165595, 3738.513993180869, 3745.7190710696805, 3750.212455384345, 3750.2312026034597, 3751.981240093782, 4995.625328100407, 5231.398389185254, 5250.553702935217, 5263.184206447969, 5294.814681486168, 3810.161764411825, 3815.294170578452, 3821.541415030214, 3834.539855217392, 3839.0786211309087, 3858.479105320746, 3873.0511024799102, 3879.8060096995036, 3884.1066554692015, 3885.611438856148, 4280.80736366858, 4286.249906266453, 4303.505096913832, 4366.6506679465565, 4370.21336159488, 4763.571278584944, 4961.231009193581, 4982.501749824988, 5013.418089683816, 5026.421961474477, 1320.3002408723391, 1323.7154011887299, 1329.8470675872204, 1332.780091284927, 1333.3533236380347, 849.4436144325476, 855.8716192571164, 855.9443636163629, 866.1338661338661, 894.1371576428777, 4366.366876802909, 4372.650187984954, 4398.69209155356, 4404.713693609905, 4418.293073108287, 5040.49595040493, 5049.671771334852, 5071.442141364447, 5083.440821509631, 5097.260897169889, 4253.553791710771, 4402.075564131568, 4402.229609818333, 4409.228385032607, 4416.867493975186, 2695.272276485377, 2700.5814098814717, 2717.266338088705, 2719.225020869043, 2720.9591856122174, 4771.880702982384, 4818.4217893852965, 4829.420469543677, 4830.0, 4839.491853355347, 4929.852104436873, 5174.560162386164, 5180.507851754077, 5202.453742357074, 5202.791888324425, 4883.389576302962, 4917.262410638433, 4939.75301234944, 4948.1835497142565, 4950.455449900508, 5143.434222235626, 5151.407588127413, 5196.766145523537, 5197.740112994411, 5215.8614967862995, 4885.853424397274, 5098.770555324945, 5119.308893299357, 5124.180131178993, 5133.972081027899, 4884.48712885142, 4959.380077490314, 4970.527799859007, 4975.7014579123925, 4979.551840334242, 3652.634736526326, 3661.139632186381, 3679.6753168858654, 3680.1167719747605, 3695.7782533048103, 4922.793915490728, 5197.856471576247, 5207.140821764367, 5208.739563021758, 5224.451432599522, 5007.7245751484015, 5008.2738002988635, 5047.318611987334, 5051.87370315745, 5057.0, 5028.723420211923, 5046.596272298134, 5058.721770302669, 5059.380998080579, 5060.493950605058, 4839.758012099452, 4990.575801056805, 4990.975045124751, 4994.200927851526, 5007.24891266313, 4863.179355973456, 4954.779393394322, 5050.520200580939, 5053.69677819332, 5115.25828754821, 4906.509349065065, 4911.140550403737, 4922.704637721605, 4949.232868905326, 4949.232868905326], "value": 3800.9275308115452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.4913174669784, 713.7537549545405, 715.7387553542957, 728.2062551818517, 745.4818900863902, 12173.573924912629, 13054.869451305483, 13069.738605227902, 13086.47654093835, 13137.080404371674, 12439.067069969744, 12555.937220313908, 12640.304783236941, 12645.936770316159, 12857.100002999794, 644.0950464597248, 813.4753084260649, 818.1164342510104, 828.0394742099166, 828.5774255129897, 783.6395258181243, 819.1399031017407, 820.1265652080544, 823.6705317872838, 825.2572684583893, 858.7380848841101, 860.6944534690189, 861.1259571534897, 862.3532350736947, 863.6372723456163, 655.577152736487, 803.493798906685, 850.345234169689, 850.6512329944724, 851.2126283188057, 614.8985417406122, 621.4748537485817, 622.0296337713182, 622.6108682073703, 622.7944778223175, 12990.0, 13044.934775326063, 13052.804207936793, 13058.542950996669, 13064.738705225842, 12069.758604827943, 12529.624111276677, 12688.30214338211, 12689.302088385135, 12701.031340142294, 11153.219274650777, 11205.775884482264, 11379.431028448544, 11549.6535103947, 11603.535858565647, 4135.0063611644555, 4160.126373461577, 4197.538270790211, 4246.639035681971, 4277.165952639237, 46685.53314466846, 46705.5329446708, 46806.531934680555, 46839.29741053887, 46848.76575617134, 9346.766330841647, 9622.51887405627, 9822.214222862154, 9947.45289009097, 10043.548040338057, 16450.34198632065, 16475.505734827973, 16480.670386592203, 16494.505164845075, 16496.917515412348, 6765.560238584476, 6779.694913728894, 6811.727530898807, 6876.587404755731, 7002.509824312249, 3785.299719551892, 3821.6560509554274, 3897.8830635080994, 3940.0937784309767, 3940.9802950985636, 2748.051922086877, 2850.7149285071328, 2857.9997001049464, 2858.3568697042997, 2862.4990626640256, 504.41235960106064, 523.0506630465768, 523.3484312031545, 533.7598080863627, 563.7829435667297, 570.1277046119444, 572.6678526454647, 580.196427947296, 592.5082181789105, 601.2514419547691, 6962.930370696329, 7177.569345839266, 7314.26857314275, 7461.992630994855, 8059.153788852203, 469.1133757198897, 576.495566379418, 580.6574121268387, 611.455804334143, 618.5639124417276, 3935.685145188378, 4040.3939409088284, 4059.6346328829954, 4085.9182816343505, 4163.83344666216, 61448.0, 62015.37984620096, 62502.0, 63060.68469657622, 63484.0, 25286.49427011486, 25412.110576129748, 25654.230373088838, 25812.0, 49379.25931111036, 49407.0, 50531.0, 50756.23865642018, 51146.48853511491, 51604.22593661098, 7181.461390395637, 7237.469481903922, 7362.558246505013, 7466.701331946661, 7993.0, 32703.34593308168, 33703.3259334807, 34072.82963585166, 34141.65858341484, 34481.82759086188, 41471.0, 42610.36084458797, 44643.19638446981, 44777.77611112054, 44851.32723009092, 9939.0, 9989.350692204984, 10153.746156346151, 10216.489175541343, 10279.17766578657, 10642.0, 10908.61819836302, 10938.507767150339, 10989.395583242678, 11348.432578371214, 10187.847182292124, 10193.23550733683, 10242.59029638836, 10378.0, 10491.94754026225, 9961.302708810314, 10029.949850250994, 10096.545655445376, 10111.39331640104, 10300.381977081399, 9366.81266374655, 9458.0, 9778.608855645694, 9784.608615655294, 9832.55753491109, 8781.648734050566, 8824.602892869965, 8909.821803564022, 8960.507172105597, 9183.586738596912, 8709.91290087091, 8759.16787905148, 8971.551422428984, 9027.954860225656, 9073.228775554151], "value": 5934.094908877592, "unit": "score"}], "name": "dromaeo_css", "value": 6335.906920275048, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 110.0, 116.0, 113.0, 115.0, 114.0, 114.0, 115.0, 116.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 131.0, 131.0, 215.0, 132.0, 143.0, 153.0, 130.0, 164.0, 129.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 161.0, 162.0, 162.0, 160.0, 162.0, 158.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 73.0, 139.0, 76.0, 77.0, 76.0, 76.0, 129.0, 74.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 163.0, 94.0, 94.0, 102.0, 127.0, 93.0, 93.0, 124.0], "value": 107.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 116.0, 62.0, 65.0], "value": 69.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 94.0, 85.0, 83.0, 83.0, 83.0, 82.0, 90.0, 90.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 144.0, 130.0, 130.0, 141.0, 128.0, 128.0, 137.0, 196.0, 142.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 174.0, 170.0, 174.0, 168.0, 173.0, 174.0, 172.0, 169.0, 169.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 67.0, 72.0, 67.0, 65.0, 68.0, 65.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1575.6, "alertThreshold": 2.0}]} 22:13:18 INFO - Return code: 0 22:13:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:13:18 ERROR - # TBPL SUCCESS # 22:13:18 INFO - Running post-action listener: _resource_record_post_action 22:13:18 INFO - ##### 22:13:18 INFO - ##### Finished run-tests step (success) 22:13:18 INFO - ##### 22:13:18 INFO - Running post-run listener: _resource_record_post_run 22:13:18 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530841600; Read time: 16; Write time: 593416 22:13:18 INFO - TinderboxPrint: CPU usage
13.1% 22:13:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:13:18 INFO - TinderboxPrint: I/O write bytes / time
530,841,600 / 593,416 22:13:18 INFO - TinderboxPrint: CPU idle
8,330.8 (86.9%) 22:13:18 INFO - TinderboxPrint: CPU user
1,212.0 (12.6%) 22:13:18 INFO - TinderboxPrint: Swap in / out
0 / 0 22:13:18 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236695552; Read time: 0; Write time: 307996 22:13:18 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294092800; Read time: 16; Write time: 285396 22:13:19 INFO - Running post-run listener: _upload_blobber_files 22:13:19 INFO - Blob upload gear active. 22:13:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:13: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 22:13: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'] 22:13: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 22:13:19 INFO - (blobuploader) - INFO - Open directory for files ... 22:13:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:13:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:13:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:20 INFO - (blobuploader) - INFO - Done attempting. 22:13:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:13:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:13:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:21 INFO - (blobuploader) - INFO - Done attempting. 22:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:13:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:13:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:13:27 INFO - (blobuploader) - INFO - Done attempting. 22:13:27 INFO - (blobuploader) - INFO - Iteration through files over. 22:13:27 INFO - Return code: 0 22:13:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:13:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:13:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1fa867870f9458edbba07f596f2d9989e30cf782eb0b9030d535846e7b5dc81bed488f46f7395672d376a639c5dc8c4f672f040dc05fa6bafacf8fc21909596", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/011d76ec1ac5e89e807e6b0fe91335da0a7959632268fd72ed2f6112e892e2d0b6fd234ea9cf30c975b6f519e26008c6df26825f577ef4d0caf2b5280c3fb052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58dfc0502e905f31954b1da3968da7ef4f32e0460723b64a9fd52d78b784413c93eb4d6bff0a254e2c2d562735860d80f4a66e59a69c05efd1bc22aab2f0bc52"} 22:13:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:13:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:13:27 INFO - Contents: 22:13:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1fa867870f9458edbba07f596f2d9989e30cf782eb0b9030d535846e7b5dc81bed488f46f7395672d376a639c5dc8c4f672f040dc05fa6bafacf8fc21909596", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/011d76ec1ac5e89e807e6b0fe91335da0a7959632268fd72ed2f6112e892e2d0b6fd234ea9cf30c975b6f519e26008c6df26825f577ef4d0caf2b5280c3fb052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58dfc0502e905f31954b1da3968da7ef4f32e0460723b64a9fd52d78b784413c93eb4d6bff0a254e2c2d562735860d80f4a66e59a69c05efd1bc22aab2f0bc52"} 22:13:27 INFO - Running post-run listener: copy_logs_to_upload_dir 22:13:27 INFO - Copying logs to upload dir... 22:13:27 INFO - mkdir: /builds/slave/test/build/upload/logs 22:13:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.177066 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-05-20 22:13:26.921499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:13:26.926594) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 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/c1fa867870f9458edbba07f596f2d9989e30cf782eb0b9030d535846e7b5dc81bed488f46f7395672d376a639c5dc8c4f672f040dc05fa6bafacf8fc21909596", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/011d76ec1ac5e89e807e6b0fe91335da0a7959632268fd72ed2f6112e892e2d0b6fd234ea9cf30c975b6f519e26008c6df26825f577ef4d0caf2b5280c3fb052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58dfc0502e905f31954b1da3968da7ef4f32e0460723b64a9fd52d78b784413c93eb4d6bff0a254e2c2d562735860d80f4a66e59a69c05efd1bc22aab2f0bc52"} build_url:https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/K1hytd8PS0CZh5ykp34Evg/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/c1fa867870f9458edbba07f596f2d9989e30cf782eb0b9030d535846e7b5dc81bed488f46f7395672d376a639c5dc8c4f672f040dc05fa6bafacf8fc21909596", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/011d76ec1ac5e89e807e6b0fe91335da0a7959632268fd72ed2f6112e892e2d0b6fd234ea9cf30c975b6f519e26008c6df26825f577ef4d0caf2b5280c3fb052", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58dfc0502e905f31954b1da3968da7ef4f32e0460723b64a9fd52d78b784413c93eb4d6bff0a254e2c2d562735860d80f4a66e59a69c05efd1bc22aab2f0bc52"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 22:13:26.955351) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:13:26.955718) ========= 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=c6927cf182745ad25b1d38b9000001fe-1463805968.714021-875004773 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004454 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 22:13:27.038566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:13:27.038895) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 22:13:27.039178) ========= ========= Total master_lag: 0.20 =========