builder: larch_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1464635853.44 results: success (0) buildid: 20160530110248 builduid: 27898311a9054a0eafda73768947c8d0 revision: dc52f6553083a5754437349d51accca8c9bd8814 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.443183) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.443647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.443966) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.623228) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.623500) ========= 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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-30 12:17:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-05-30 12:17:33 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079358 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.790802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.791091) ========= 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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014594 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.821243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:33.821562) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev dc52f6553083a5754437349d51accca8c9bd8814 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev dc52f6553083a5754437349d51accca8c9bd8814 --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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-30 12:17:34,120 truncating revision to first 12 chars 2016-05-30 12:17:34,120 Setting DEBUG logging. 2016-05-30 12:17:34,120 attempt 1/10 2016-05-30 12:17:34,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/dc52f6553083?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 12:17:34,427 unpacking tar archive at: larch-dc52f6553083/testing/mozharness/ program finished with exit code 0 elapsedTime=0.703584 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:34.538391) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:34.538793) ========= script_repo_revision: dc52f6553083a5754437349d51accca8c9bd8814 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:34.539296) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:34.539605) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:17:34.552987) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-30 12:17:34.553255) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-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', 'Larch-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:17:34 INFO - MultiFileLogger online at 20160530 12:17:34 in /builds/slave/test 12:17:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 12:17:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:34 INFO - {'append_to_log': False, 12:17:34 INFO - 'base_work_dir': '/builds/slave/test', 12:17:34 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 12:17:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:34 INFO - 'branch': 'Larch-Non-PGO', 12:17:34 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:34 INFO - 'config_files': ('talos/linux_config.py',), 12:17:34 INFO - 'default_actions': ('clobber', 12:17:34 INFO - 'read-buildbot-config', 12:17:34 INFO - 'download-and-extract', 12:17:34 INFO - 'populate-webroot', 12:17:34 INFO - 'create-virtualenv', 12:17:34 INFO - 'install', 12:17:34 INFO - 'run-tests'), 12:17:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:34 INFO - 'download_minidump_stackwalk': True, 12:17:34 INFO - 'download_symbols': 'ondemand', 12:17:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:17:34 INFO - 'tooltool.py': '/tools/tooltool.py', 12:17:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:17:34 INFO - '/tools/misc-python/virtualenv.py')}, 12:17:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:34 INFO - 'installer_path': 'installer.exe', 12:17:34 INFO - 'log_level': 'info', 12:17:34 INFO - 'log_name': 'talos', 12:17:34 INFO - 'log_to_console': True, 12:17:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:17:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:17:34 INFO - 'opt_config_files': (), 12:17:34 INFO - 'pip_index': False, 12:17:34 INFO - 'sps_profile': False, 12:17:34 INFO - 'sps_profile_interval': 0, 12:17:34 INFO - 'suite': 'dromaeojs', 12:17:34 INFO - 'system_bits': '32', 12:17:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:34 INFO - 'title': 'talos-linux64-ix-053', 12:17:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:34 INFO - 'use_talos_json': True, 12:17:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:34 INFO - 'work_dir': 'build'} 12:17:34 INFO - ##### 12:17:34 INFO - ##### Running clobber step. 12:17:34 INFO - ##### 12:17:34 INFO - Running pre-action listener: _resource_record_pre_action 12:17:34 INFO - Running main action method: clobber 12:17:34 INFO - rmtree: /builds/slave/test/build 12:17:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:35 INFO - Running post-action listener: _resource_record_post_action 12:17:35 INFO - ##### 12:17:35 INFO - ##### Finished clobber step (success) 12:17:35 INFO - ##### 12:17:35 INFO - ##### 12:17:35 INFO - ##### Running read-buildbot-config step. 12:17:35 INFO - ##### 12:17:35 INFO - Running pre-action listener: _resource_record_pre_action 12:17:35 INFO - Running main action method: read_buildbot_config 12:17:35 INFO - Using buildbot properties: 12:17:35 INFO - { 12:17:35 INFO - "project": "", 12:17:35 INFO - "product": "firefox", 12:17:35 INFO - "script_repo_revision": "production", 12:17:35 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 12:17:35 INFO - "repository": "", 12:17:35 INFO - "buildername": "Ubuntu HW 12.04 x64 larch talos dromaeojs", 12:17:35 INFO - "buildid": "20160530110248", 12:17:35 INFO - "pgo_build": "False", 12:17:35 INFO - "basedir": "/builds/slave/test", 12:17:35 INFO - "buildnumber": 3, 12:17:35 INFO - "slavename": "talos-linux64-ix-053", 12:17:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:17:35 INFO - "platform": "ubuntu64_hw", 12:17:35 INFO - "scheduler": "tests-larch-linux64-talos", 12:17:35 INFO - "branch": "larch", 12:17:35 INFO - "repo_path": "projects/larch", 12:17:35 INFO - "revision": "dc52f6553083a5754437349d51accca8c9bd8814", 12:17:35 INFO - "stage_platform": "linux64", 12:17:35 INFO - "builduid": "27898311a9054a0eafda73768947c8d0", 12:17:35 INFO - "slavebuilddir": "test" 12:17:35 INFO - } 12:17:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 12:17:35 INFO - Running post-action listener: _resource_record_post_action 12:17:35 INFO - ##### 12:17:35 INFO - ##### Finished read-buildbot-config step (success) 12:17:35 INFO - ##### 12:17:35 INFO - ##### 12:17:35 INFO - ##### Running download-and-extract step. 12:17:35 INFO - ##### 12:17:35 INFO - Running pre-action listener: _resource_record_pre_action 12:17:35 INFO - Running main action method: download_and_extract 12:17:35 INFO - mkdir: /builds/slave/test/build/tests 12:17: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')]} 12:17:35 INFO - https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 12:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:43 INFO - Downloaded 1361 bytes. 12:17:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 12:17:43 INFO - Using the following test package requirements: 12:17:43 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:17:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:17:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:17:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'jsshell-linux-x86_64.zip'], 12:17:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:17:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 12:17:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:17:43 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:17:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:17:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 12:17:43 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:17:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:43 INFO - https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:17:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:17:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:17:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:49 INFO - Downloaded 22848162 bytes. 12:17:49 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'] 12:17:49 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 12:17:50 INFO - Return code: 0 12:17:50 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 12:17: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')]} 12:17:50 INFO - https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 12:17:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:17:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 12:17:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:52 INFO - Downloaded 22848162 bytes. 12:17:52 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'] 12:17:52 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 12:17:53 INFO - Return code: 0 12:17:53 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')]} 12:17:53 INFO - https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 12:17:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:17:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 12:17:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:54 INFO - Downloaded 10834042 bytes. 12:17:54 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'] 12:17:54 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 12:17:54 INFO - Return code: 0 12:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:17:54 INFO - https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 12:17:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:17:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:17:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/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 12:17:56 INFO - Downloaded 54913503 bytes. 12:17:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:17:56 INFO - mkdir: /builds/slave/test/properties 12:17:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:17:56 INFO - Writing to file /builds/slave/test/properties/build_url 12:17:56 INFO - Contents: 12:17:56 INFO - build_url:https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 12:17:57 INFO - Running post-action listener: _resource_record_post_action 12:17:57 INFO - Running post-action listener: set_extra_try_arguments 12:17:57 INFO - ##### 12:17:57 INFO - ##### Finished download-and-extract step (success) 12:17:57 INFO - ##### 12:17:57 INFO - ##### 12:17:57 INFO - ##### Running populate-webroot step. 12:17:57 INFO - ##### 12:17:57 INFO - Running pre-action listener: _resource_record_pre_action 12:17:57 INFO - Running main action method: populate_webroot 12:17:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:17:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:17:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:17:57 INFO - '--tppagecycles', 12:17:57 INFO - '7'], 12:17:57 INFO - 'tests': ['tsvgm']}}, 12:17:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:17:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:17:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:17:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:17:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['damp', 'tps']}, 12:17:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'tests': ['damp', 'tps']}, 12:17:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['dromaeo_dom']}, 12:17:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:17:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'other-e10s': {'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['a11yr', 12:17:57 INFO - 'ts_paint', 12:17:57 INFO - 'tpaint', 12:17:57 INFO - 'sessionrestore', 12:17:57 INFO - 'sessionrestore_no_auto_restore', 12:17:57 INFO - 'tabpaint']}, 12:17:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['tsvgx', 12:17:57 INFO - 'tsvgr_opacity', 12:17:57 INFO - 'tart', 12:17:57 INFO - 'tscrollx', 12:17:57 INFO - 'cart']}, 12:17:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:17:57 INFO - 'tsvgr_opacity', 12:17:57 INFO - 'tart', 12:17:57 INFO - 'tscrollx', 12:17:57 INFO - 'cart']}, 12:17:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'talos_options': ['--disable-e10s'], 12:17:57 INFO - 'tests': ['tp5o']}, 12:17:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'tests': ['tp5o']}, 12:17:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'talos_options': ['--disable-e10s', 12:17:57 INFO - '--xperf_path', 12:17:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:57 INFO - 'tests': ['tp5n']}, 12:17:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:57 INFO - 'talos_options': ['--xperf_path', 12:17:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:57 INFO - 'tests': ['tp5n']}}, 12:17:57 INFO - 'talos.zip': {'path': '', 12:17:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:17:57 INFO - Running post-action listener: _resource_record_post_action 12:17:57 INFO - ##### 12:17:57 INFO - ##### Finished populate-webroot step (success) 12:17:57 INFO - ##### 12:17:57 INFO - ##### 12:17:57 INFO - ##### Running create-virtualenv step. 12:17:57 INFO - ##### 12:17:57 INFO - Running pre-action listener: _resource_record_pre_action 12:17:57 INFO - Running main action method: create_virtualenv 12:17:57 INFO - Creating virtualenv /builds/slave/test/build/venv 12:17:57 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 12:17:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:17:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:17:57 INFO - Using real prefix '/usr' 12:17:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:17:57 INFO - Installing distribute.............................................................................................................................................................................................done. 12:17:59 INFO - Installing pip.................done. 12:17:59 INFO - Return code: 0 12:17:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:17:59 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')]} 12:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 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')]} 12:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:17:59 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 12:17:59 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 12:17:59 INFO - Using env: {'DISPLAY': ':0', 12:17:59 INFO - 'HOME': '/home/cltbld', 12:17:59 INFO - 'LANG': 'en_US.UTF-8', 12:17:59 INFO - 'LANGUAGE': 'en_US:en', 12:17:59 INFO - 'LOGNAME': 'cltbld', 12:17:59 INFO - 'MAIL': '/var/mail/cltbld', 12:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:59 INFO - 'MOZ_NO_REMOTE': '1', 12:17:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:59 INFO - 'NO_EM_RESTART': '1', 12:17:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:59 INFO - 'PWD': '/builds/slave/test', 12:17:59 INFO - 'SHELL': '/bin/bash', 12:17:59 INFO - 'SHLVL': '1', 12:17:59 INFO - 'TERM': 'linux', 12:17:59 INFO - 'TMOUT': '86400', 12:17:59 INFO - 'USER': 'cltbld', 12:17:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:59 INFO - '_': '/tools/buildbot/bin/python'} 12:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:59 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 12:17:59 INFO - Cleaning up... 12:17:59 INFO - Return code: 0 12:17:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:17:59 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')]} 12:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 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')]} 12:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:59 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:17:59 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 12:17:59 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 12:17:59 INFO - Using env: {'DISPLAY': ':0', 12:17:59 INFO - 'HOME': '/home/cltbld', 12:17:59 INFO - 'LANG': 'en_US.UTF-8', 12:17:59 INFO - 'LANGUAGE': 'en_US:en', 12:17:59 INFO - 'LOGNAME': 'cltbld', 12:17:59 INFO - 'MAIL': '/var/mail/cltbld', 12:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:59 INFO - 'MOZ_NO_REMOTE': '1', 12:17:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:17:59 INFO - 'NO_EM_RESTART': '1', 12:17:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:59 INFO - 'PWD': '/builds/slave/test', 12:17:59 INFO - 'SHELL': '/bin/bash', 12:17:59 INFO - 'SHLVL': '1', 12:17:59 INFO - 'TERM': 'linux', 12:17:59 INFO - 'TMOUT': '86400', 12:17:59 INFO - 'USER': 'cltbld', 12:17:59 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:59 INFO - '_': '/tools/buildbot/bin/python'} 12:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:00 INFO - Downloading/unpacking psutil>=3.1.1 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:18:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:02 INFO - Installing collected packages: psutil 12:18:02 INFO - Running setup.py install for psutil 12:18:02 INFO - building 'psutil._psutil_linux' extension 12:18:02 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 12:18:02 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 12:18:02 INFO - building 'psutil._psutil_posix' extension 12:18:02 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 12:18:02 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 12:18:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:02 INFO - Successfully installed psutil 12:18:02 INFO - Cleaning up... 12:18:02 INFO - Return code: 0 12:18:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:18:02 INFO - Using env: {'DISPLAY': ':0', 12:18:02 INFO - 'HOME': '/home/cltbld', 12:18:02 INFO - 'LANG': 'en_US.UTF-8', 12:18:02 INFO - 'LANGUAGE': 'en_US:en', 12:18:02 INFO - 'LOGNAME': 'cltbld', 12:18:02 INFO - 'MAIL': '/var/mail/cltbld', 12:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:02 INFO - 'MOZ_NO_REMOTE': '1', 12:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:02 INFO - 'NO_EM_RESTART': '1', 12:18:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:02 INFO - 'PWD': '/builds/slave/test', 12:18:02 INFO - 'SHELL': '/bin/bash', 12:18:02 INFO - 'SHLVL': '1', 12:18:02 INFO - 'TERM': 'linux', 12:18:02 INFO - 'TMOUT': '86400', 12:18:02 INFO - 'USER': 'cltbld', 12:18:02 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:02 INFO - '_': '/tools/buildbot/bin/python'} 12:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:18:04 INFO - Installing collected packages: mozsystemmonitor 12:18:04 INFO - Running setup.py install for mozsystemmonitor 12:18:04 INFO - Successfully installed mozsystemmonitor 12:18:04 INFO - Cleaning up... 12:18:04 INFO - Return code: 0 12:18:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:18:05 INFO - Using env: {'DISPLAY': ':0', 12:18:05 INFO - 'HOME': '/home/cltbld', 12:18:05 INFO - 'LANG': 'en_US.UTF-8', 12:18:05 INFO - 'LANGUAGE': 'en_US:en', 12:18:05 INFO - 'LOGNAME': 'cltbld', 12:18:05 INFO - 'MAIL': '/var/mail/cltbld', 12:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:05 INFO - 'MOZ_NO_REMOTE': '1', 12:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:05 INFO - 'NO_EM_RESTART': '1', 12:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:05 INFO - 'PWD': '/builds/slave/test', 12:18:05 INFO - 'SHELL': '/bin/bash', 12:18:05 INFO - 'SHLVL': '1', 12:18:05 INFO - 'TERM': 'linux', 12:18:05 INFO - 'TMOUT': '86400', 12:18:05 INFO - 'USER': 'cltbld', 12:18:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:05 INFO - '_': '/tools/buildbot/bin/python'} 12:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:05 INFO - Downloading/unpacking blobuploader==1.2.4 12:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:07 INFO - Downloading blobuploader-1.2.4.tar.gz 12:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:18:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:18:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:07 INFO - Downloading docopt-0.6.1.tar.gz 12:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:18:07 INFO - Installing collected packages: blobuploader, requests, docopt 12:18:07 INFO - Running setup.py install for blobuploader 12:18:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:18:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:18:07 INFO - Running setup.py install for requests 12:18:08 INFO - Running setup.py install for docopt 12:18:08 INFO - Successfully installed blobuploader requests docopt 12:18:08 INFO - Cleaning up... 12:18:08 INFO - Return code: 0 12:18:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:08 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:08 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 12:18:08 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 12:18:08 INFO - Using env: {'DISPLAY': ':0', 12:18:08 INFO - 'HOME': '/home/cltbld', 12:18:08 INFO - 'LANG': 'en_US.UTF-8', 12:18:08 INFO - 'LANGUAGE': 'en_US:en', 12:18:08 INFO - 'LOGNAME': 'cltbld', 12:18:08 INFO - 'MAIL': '/var/mail/cltbld', 12:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:08 INFO - 'MOZ_NO_REMOTE': '1', 12:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:08 INFO - 'NO_EM_RESTART': '1', 12:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:08 INFO - 'PWD': '/builds/slave/test', 12:18:08 INFO - 'SHELL': '/bin/bash', 12:18:08 INFO - 'SHLVL': '1', 12:18:08 INFO - 'TERM': 'linux', 12:18:08 INFO - 'TMOUT': '86400', 12:18:08 INFO - 'USER': 'cltbld', 12:18:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:08 INFO - '_': '/tools/buildbot/bin/python'} 12:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:08 INFO - Running setup.py (path:/tmp/pip-S9luX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:08 INFO - Running setup.py (path:/tmp/pip-FvSMlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:08 INFO - Running setup.py (path:/tmp/pip-4W1Cnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:08 INFO - Running setup.py (path:/tmp/pip-tNEoMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:08 INFO - Running setup.py (path:/tmp/pip-RHkcx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:09 INFO - Running setup.py (path:/tmp/pip-pmHdMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:09 INFO - Running setup.py (path:/tmp/pip-yIViGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:09 INFO - Running setup.py (path:/tmp/pip-li8jgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:09 INFO - Running setup.py (path:/tmp/pip-aZFh3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:09 INFO - Running setup.py (path:/tmp/pip-Rlk9UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:09 INFO - Running setup.py (path:/tmp/pip-QoqfVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:09 INFO - Running setup.py (path:/tmp/pip-XChA6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:09 INFO - Running setup.py (path:/tmp/pip-qnt9Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:09 INFO - Running setup.py (path:/tmp/pip-DvcPSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:09 INFO - Running setup.py (path:/tmp/pip-uXsNtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:09 INFO - Running setup.py (path:/tmp/pip-s9spWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:09 INFO - Running setup.py (path:/tmp/pip-WrfJdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:09 INFO - Running setup.py install for manifestparser 12:18:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:09 INFO - Running setup.py install for mozcrash 12:18:10 INFO - Running setup.py install for mozdebug 12:18:10 INFO - Running setup.py install for mozdevice 12:18:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Running setup.py install for mozfile 12:18:10 INFO - Running setup.py install for mozhttpd 12:18:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Running setup.py install for mozinfo 12:18:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Running setup.py install for mozInstall 12:18:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Running setup.py install for mozleak 12:18:10 INFO - Running setup.py install for mozlog 12:18:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:10 INFO - Running setup.py install for moznetwork 12:18:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Running setup.py install for mozprocess 12:18:11 INFO - Running setup.py install for mozprofile 12:18:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Running setup.py install for mozrunner 12:18:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Running setup.py install for mozscreenshot 12:18:11 INFO - Running setup.py install for moztest 12:18:11 INFO - Running setup.py install for mozversion 12:18:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:18:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:18:11 INFO - Cleaning up... 12:18:11 INFO - Return code: 0 12:18:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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')]} 12:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:11 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:11 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 12:18:11 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 12:18:11 INFO - Using env: {'DISPLAY': ':0', 12:18:11 INFO - 'HOME': '/home/cltbld', 12:18:11 INFO - 'LANG': 'en_US.UTF-8', 12:18:11 INFO - 'LANGUAGE': 'en_US:en', 12:18:11 INFO - 'LOGNAME': 'cltbld', 12:18:11 INFO - 'MAIL': '/var/mail/cltbld', 12:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:11 INFO - 'MOZ_NO_REMOTE': '1', 12:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:11 INFO - 'NO_EM_RESTART': '1', 12:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:11 INFO - 'PWD': '/builds/slave/test', 12:18:11 INFO - 'SHELL': '/bin/bash', 12:18:11 INFO - 'SHLVL': '1', 12:18:11 INFO - 'TERM': 'linux', 12:18:11 INFO - 'TMOUT': '86400', 12:18:11 INFO - 'USER': 'cltbld', 12:18:11 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:11 INFO - '_': '/tools/buildbot/bin/python'} 12:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:11 INFO - Running setup.py (path:/tmp/pip-wWQ8vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:11 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)) 12:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:11 INFO - Running setup.py (path:/tmp/pip-QwZUVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:12 INFO - Running setup.py (path:/tmp/pip-nPcGfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:12 INFO - Running setup.py (path:/tmp/pip-uXT1ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:12 INFO - Running setup.py (path:/tmp/pip-hxVCsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:12 INFO - Running setup.py (path:/tmp/pip-imktc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:12 INFO - Running setup.py (path:/tmp/pip-4QwSxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:12 INFO - Running setup.py (path:/tmp/pip-ynnvxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:12 INFO - Running setup.py (path:/tmp/pip-4us9Ny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:12 INFO - Running setup.py (path:/tmp/pip-3NZ9rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:12 INFO - Running setup.py (path:/tmp/pip-bbLy7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:12 INFO - Running setup.py (path:/tmp/pip-S7MLoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:12 INFO - Running setup.py (path:/tmp/pip-fgc8gB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:12 INFO - Running setup.py (path:/tmp/pip-1llpfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:12 INFO - Running setup.py (path:/tmp/pip-iopLkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:12 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)) 12:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:12 INFO - Running setup.py (path:/tmp/pip-gu52CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:13 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)) 12:18:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:13 INFO - Running setup.py (path:/tmp/pip-bu8tss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:13 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)) 12:18:13 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)) 12:18:13 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)) 12:18:13 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)) 12:18:13 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)) 12:18:13 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)) 12:18:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:15 INFO - Downloading blessings-1.6.tar.gz 12:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:18:15 INFO - Installing collected packages: blessings 12:18:15 INFO - Running setup.py install for blessings 12:18:15 INFO - Successfully installed blessings 12:18:15 INFO - Cleaning up... 12:18:15 INFO - Return code: 0 12:18:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:15 INFO - Reading from file tmpfile_stdout 12:18:15 INFO - Current package versions: 12:18:15 INFO - argparse == 1.2.1 12:18:15 INFO - blessings == 1.6 12:18:15 INFO - blobuploader == 1.2.4 12:18:15 INFO - docopt == 0.6.1 12:18:15 INFO - manifestparser == 1.1 12:18:15 INFO - mozInstall == 1.12 12:18:15 INFO - mozcrash == 0.17 12:18:15 INFO - mozdebug == 0.1 12:18:15 INFO - mozdevice == 0.48 12:18:15 INFO - mozfile == 1.2 12:18:15 INFO - mozhttpd == 0.7 12:18:15 INFO - mozinfo == 0.9 12:18:15 INFO - mozleak == 0.1 12:18:15 INFO - mozlog == 3.2 12:18:15 INFO - moznetwork == 0.27 12:18:15 INFO - mozprocess == 0.22 12:18:15 INFO - mozprofile == 0.28 12:18:15 INFO - mozrunner == 6.11 12:18:15 INFO - mozscreenshot == 0.1 12:18:15 INFO - mozsystemmonitor == 0.3 12:18:15 INFO - moztest == 0.7 12:18:15 INFO - mozversion == 1.4 12:18:15 INFO - psutil == 3.1.1 12:18:15 INFO - requests == 1.2.3 12:18:15 INFO - wsgiref == 0.1.2 12:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:15 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')]} 12:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 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')]} 12:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:15 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 12:18:15 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 12:18:15 INFO - Using env: {'DISPLAY': ':0', 12:18:15 INFO - 'HOME': '/home/cltbld', 12:18:15 INFO - 'LANG': 'en_US.UTF-8', 12:18:15 INFO - 'LANGUAGE': 'en_US:en', 12:18:15 INFO - 'LOGNAME': 'cltbld', 12:18:15 INFO - 'MAIL': '/var/mail/cltbld', 12:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:15 INFO - 'MOZ_NO_REMOTE': '1', 12:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:15 INFO - 'NO_EM_RESTART': '1', 12:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:15 INFO - 'PWD': '/builds/slave/test', 12:18:15 INFO - 'SHELL': '/bin/bash', 12:18:15 INFO - 'SHLVL': '1', 12:18:15 INFO - 'TERM': 'linux', 12:18:15 INFO - 'TMOUT': '86400', 12:18:15 INFO - 'USER': 'cltbld', 12:18:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:15 INFO - '_': '/tools/buildbot/bin/python'} 12:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 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)) 12:18:15 INFO - Cleaning up... 12:18:15 INFO - Return code: 0 12:18:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:18:15 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')]} 12:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 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')]} 12:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:15 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 12:18:15 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 12:18:15 INFO - Using env: {'DISPLAY': ':0', 12:18:15 INFO - 'HOME': '/home/cltbld', 12:18:15 INFO - 'LANG': 'en_US.UTF-8', 12:18:15 INFO - 'LANGUAGE': 'en_US:en', 12:18:15 INFO - 'LOGNAME': 'cltbld', 12:18:15 INFO - 'MAIL': '/var/mail/cltbld', 12:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:15 INFO - 'MOZ_NO_REMOTE': '1', 12:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:15 INFO - 'NO_EM_RESTART': '1', 12:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:15 INFO - 'PWD': '/builds/slave/test', 12:18:15 INFO - 'SHELL': '/bin/bash', 12:18:15 INFO - 'SHLVL': '1', 12:18:15 INFO - 'TERM': 'linux', 12:18:15 INFO - 'TMOUT': '86400', 12:18:15 INFO - 'USER': 'cltbld', 12:18:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:15 INFO - '_': '/tools/buildbot/bin/python'} 12:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:15 INFO - Downloading/unpacking jsonschema 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:18:17 INFO - Downloading/unpacking functools32 (from jsonschema) 12:18:17 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 12:18:17 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 12:18:17 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 12:18:17 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 12:18:17 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 12:18:17 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 12:18:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:18:18 INFO - warning: no files found matching '*.txt' 12:18:18 INFO - no previously-included directories found matching 'build' 12:18:18 INFO - no previously-included directories found matching 'dist' 12:18:18 INFO - no previously-included directories found matching '.git*' 12:18:18 INFO - Installing collected packages: jsonschema, functools32 12:18:18 INFO - Running setup.py install for functools32 12:18:18 INFO - warning: no files found matching '*.txt' 12:18:18 INFO - no previously-included directories found matching 'build' 12:18:18 INFO - no previously-included directories found matching 'dist' 12:18:18 INFO - no previously-included directories found matching '.git*' 12:18:18 INFO - Successfully installed jsonschema functools32 12:18:18 INFO - Cleaning up... 12:18:18 INFO - Return code: 0 12:18:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:18:18 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')]} 12:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:18 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')]} 12:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:18 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 0x7fa77aee1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x198d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, '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': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', '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 12:18:18 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 12:18:18 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 12:18:18 INFO - Using env: {'DISPLAY': ':0', 12:18:18 INFO - 'HOME': '/home/cltbld', 12:18:18 INFO - 'LANG': 'en_US.UTF-8', 12:18:18 INFO - 'LANGUAGE': 'en_US:en', 12:18:18 INFO - 'LOGNAME': 'cltbld', 12:18:18 INFO - 'MAIL': '/var/mail/cltbld', 12:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:18 INFO - 'MOZ_NO_REMOTE': '1', 12:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:18 INFO - 'NO_EM_RESTART': '1', 12:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:18 INFO - 'PWD': '/builds/slave/test', 12:18:18 INFO - 'SHELL': '/bin/bash', 12:18:18 INFO - 'SHLVL': '1', 12:18:18 INFO - 'TERM': 'linux', 12:18:18 INFO - 'TMOUT': '86400', 12:18:18 INFO - 'USER': 'cltbld', 12:18:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:18 INFO - '_': '/tools/buildbot/bin/python'} 12:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:18 INFO - Downloading/unpacking flake8 12:18:18 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 12:18:18 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 12:18:18 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 12:18:18 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 12:18:18 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 12:18:18 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 12:18:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:18:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:18:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:20 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 12:18:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:18:21 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 12:18:21 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 12:18:21 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 12:18:21 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 12:18:21 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 12:18:21 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 12:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:18:21 INFO - no previously-included directories found matching 'docs/_build' 12:18:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:18:21 INFO - Running setup.py install for pep8 12:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:18:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:18:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:18:21 INFO - no previously-included directories found matching 'docs/_build' 12:18:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:18:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:18:21 INFO - Cleaning up... 12:18:21 INFO - Return code: 0 12:18:21 INFO - Running post-action listener: _resource_record_post_action 12:18:21 INFO - Running post-action listener: _start_resource_monitoring 12:18:21 INFO - Starting resource monitoring. 12:18:21 INFO - ##### 12:18:21 INFO - ##### Finished create-virtualenv step (success) 12:18:21 INFO - ##### 12:18:21 INFO - ##### 12:18:21 INFO - ##### Running install step. 12:18:21 INFO - ##### 12:18:21 INFO - Running pre-action listener: _resource_record_pre_action 12:18:21 INFO - Running main action method: install 12:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:21 INFO - Reading from file tmpfile_stdout 12:18:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:18:21 INFO - Reading from file tmpfile_stdout 12:18:21 INFO - Output received: 12:18:21 INFO - Usage: mozinstall [options] installer 12:18:21 INFO - Options: 12:18:21 INFO - -h, --help show this help message and exit 12:18:21 INFO - -d DEST, --destination=DEST 12:18:21 INFO - Directory to install application into. [default: 12:18:21 INFO - "/builds/slave/test"] 12:18:21 INFO - --app=APP Application being installed. [default: firefox] 12:18:21 INFO - mkdir: /builds/slave/test/build/application 12:18:21 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'] 12:18:21 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 12:18:37 INFO - Reading from file tmpfile_stdout 12:18:37 INFO - Output received: 12:18:37 INFO - /builds/slave/test/build/application/firefox/firefox 12:18:37 INFO - Running post-action listener: _resource_record_post_action 12:18:37 INFO - ##### 12:18:37 INFO - ##### Finished install step (success) 12:18:37 INFO - ##### 12:18:37 INFO - ##### 12:18:37 INFO - ##### Running run-tests step. 12:18:37 INFO - ##### 12:18:37 INFO - Running pre-action listener: _resource_record_pre_action 12:18:37 INFO - Running main action method: run_tests 12:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:18:37 INFO - Python 2.7.3 12:18:37 INFO - Return code: 0 12:18:37 INFO - grabbing minidump binary from tooltool 12:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:37 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 0x1ae79c0>, '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 0x1ae88c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ae8d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:18:37 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 12:18:37 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 12:18:37 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 12:18:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:18:37 INFO - Return code: 0 12:18:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:18:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:18:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:18:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:18:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:18:38 INFO - Return code: 0 12:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 12:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 12:18:38 INFO - Using env: {'DISPLAY': ':0', 12:18:38 INFO - 'HOME': '/home/cltbld', 12:18:38 INFO - 'LANG': 'en_US.UTF-8', 12:18:38 INFO - 'LANGUAGE': 'en_US:en', 12:18:38 INFO - 'LOGNAME': 'cltbld', 12:18:38 INFO - 'MAIL': '/var/mail/cltbld', 12:18:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:38 INFO - 'MOZ_NO_REMOTE': '1', 12:18:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:18:38 INFO - 'NO_EM_RESTART': '1', 12:18:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:38 INFO - 'PWD': '/builds/slave/test', 12:18:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:38 INFO - 'SHELL': '/bin/bash', 12:18:38 INFO - 'SHLVL': '1', 12:18:38 INFO - 'TERM': 'linux', 12:18:38 INFO - 'TMOUT': '86400', 12:18:38 INFO - 'USER': 'cltbld', 12:18:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285', 12:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:38 INFO - '_': '/tools/buildbot/bin/python'} 12:18:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 12:18:38 INFO - mozversion application_buildid: 20160530110248 12:18:38 INFO - mozversion application_changeset: dc52f6553083a5754437349d51accca8c9bd8814 12:18:38 INFO - mozversion application_display_name: Nightly 12:18:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:18:38 INFO - mozversion application_name: Firefox 12:18:38 INFO - mozversion application_remotingname: firefox 12:18:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 12:18:38 INFO - mozversion application_vendor: Mozilla 12:18:38 INFO - mozversion application_version: 49.0a1 12:18:38 INFO - mozversion platform_buildid: 20160530110248 12:18:38 INFO - mozversion platform_changeset: dc52f6553083a5754437349d51accca8c9bd8814 12:18:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 12:18:38 INFO - mozversion platform_version: 49.0a1 12:18:38 INFO - using testdate: 1464635918 12:18:38 INFO - actual date: 1464635918 12:18:38 INFO - starting webserver on 'localhost:43905' 12:18:38 INFO - SUITE-START | Running 2 tests 12:18:38 INFO - TEST-START | dromaeo_css 12:18:38 INFO - operating with platform_type : linux_ 12:18:38 INFO - Initialising browser for dromaeo_css test... 12:18:38 INFO - TEST-INFO | started process 28442 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4rbEDk/profile http://localhost:43905/getInfo.html) 12:18:43 INFO - PROCESS | 28442 | __metrics Screen width/height:1600/1200 12:18:43 INFO - PROCESS | 28442 | colorDepth:24 12:18:43 INFO - PROCESS | 28442 | Browser inner width/height: 1024/697 12:18:43 INFO - PROCESS | 28442 | __metrics 12:18:44 ERROR - PROCESS | 28442 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:18:45 INFO - TEST-INFO | 28442: exit 0 12:18:45 INFO - Browser initialized. 12:18:45 INFO - Running cycle 1/1 for dromaeo_css test... 12:18:45 INFO - TEST-INFO | started process 28522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4rbEDk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:18:57 INFO - PROCESS | 28522 | RSS: Main: 156307456 12:18:57 INFO - PROCESS | 28522 | 12:19:49 INFO - PROCESS | 28522 | 1464635989108 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] 12:22:00 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43905/tests/dromaeo/cssquery-ext.html) 12:22:01 INFO - PROCESS | 28522 | RSS: Main: 270458880 12:22:01 INFO - PROCESS | 28522 | 12:25:04 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-ext.html (next: http://localhost:43905/tests/dromaeo/cssquery-jquery.html) 12:25:05 INFO - PROCESS | 28522 | RSS: Main: 240726016 12:25:05 INFO - PROCESS | 28522 | 12:28:07 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43905/tests/dromaeo/cssquery-mootools.html) 12:28:07 INFO - PROCESS | 28522 | RSS: Main: 244850688 12:28:07 INFO - PROCESS | 28522 | 12:31:09 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43905/tests/dromaeo/cssquery-prototype.html) 12:31:10 INFO - PROCESS | 28522 | RSS: Main: 238055424 12:31:10 INFO - PROCESS | 28522 | 12:34:13 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43905/tests/dromaeo/cssquery-yui.html) 12:34:14 INFO - PROCESS | 28522 | RSS: Main: 228524032 12:34:14 INFO - PROCESS | 28522 | 12:37:16 INFO - PROCESS | 28522 | Cycle 1(1): loaded http://localhost:43905/tests/dromaeo/cssquery-yui.html (next: http://localhost:43905/tests/dromaeo/cssquery-dojo.html) 12:37:17 INFO - PROCESS | 28522 | RSS: Main: 321662976 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | __start_tp_report 12:37:17 INFO - PROCESS | 28522 | _x_x_mozilla_page_load 12:37:17 INFO - PROCESS | 28522 | _x_x_mozilla_page_load_details 12:37:17 INFO - PROCESS | 28522 | |i|pagename|runs| 12:37:17 INFO - PROCESS | 28522 | |0;dojo.html;2659.7473240042195;2680.745329193726;2692.69034061083;2700.095468018215;2705.3777631144835;4619.237825758746;4666.183417901866;4709.670323077377;4749.26386410107;4791.832285869995;4633.768311584416;4670.112678591066;4676.158291507536;4701.929471057937;4724.3622111015;4264.936025959605;4338.370936214247;4376.1247750449875;4390.209762242795;4512.5261847506035;4436.334549817521;4450.51044385119;4460.264056430685;4490.393796837434;4511.255642818947;4217.135487225111;4271.893202669927;4313.84901528445;4364.170807546556;4398.252297109483;4331.350297455407;4348.5216626171095;4366.934495982563;4442.355858400546;4447.266201076818;4371.934420983688;4385.6710746694125;4429.379886815848;4430.291153415438;4436.0240747035605;4497.9775101124615;4561.726296422192;4638.698484598524;4642.53574642533;4734.692245004064;5153.613478989091;5171.4311425743135;5172.741362931838;5174.870628234286;5174.948250517485;5140.7172605506685;5155.871103222449;5157;5169.767360468751;5173.534381905609;4627.745473998929;4700.435947686299;4738.3129446230105;4772.0694464579765;4809.014152098812;5278.049951008856;5285.1543753000105;5291.206319052096;5291.391489978625;5292.364916210079;5047.318611987408;5052.8484145475695;5054.570361519239;5057.620678449131;5061.898762024739;4870.14772414826;4904.313396124546;4913.385826771653;4953.2074868020945;4993.226049962262;4881.51184881509;4901.411830580282;4901.58336541398;4903.068417000758;4949;4886.267059940967;4890.58430033444;4892.8532144035735;4893.168161412497;4939.234418665113;4208.032152604918;4211.642010429086;4219.177260434186;4227.661787057028;4262.808173632194;3714.257148570243;3721.5720192177705;3725.906852328659;3756.323861704919;3759.8684046058256;4374.956250437455;4377.802998865059;4383.473983121982;4412.691111622155;4423.336499525097;4932.383452068491;5042;5066.03745288394;5072.467390923974;5093.490650934951;4385.298352263686;4403.14138742942;4439.267520859086;4450.198964186413;4455.710378825366;4633.050224703928;4641.721496710208;4646.907061858744;4647.09381670571;4695.060987802453;4727.2436410174205;4780.569748722632;4813.470518242921;4884.780184891617;4901.926471103007;5105.132127538253;5112.616553758408;5117.513836185556;5117.5394214520875;5151.330327057459;4808.326834243138;4847.781849816696;4848.0546293472435;4849.927251091307;4874.171390863491;4838.758062096951;4926.285688575208;4942.901141977212;4979.601631869369;5065.4174769900455;5089.338386009943;5111.821086261963;5118.74406279692;5122.282880396673;5153.45888681698;4934.333864928189;4937.388193202232;4942.332785073969;4944.283078953604;4944.505549445027;4796.5203479653155;4860.8298709545;4914.213725803854;4922.212446008622;4928.531789479994;4832.710037397767;4842.709437433765;4924.285978533164;4925.605951523798;4931.654784165217;4877.487863774394;4982.626303027215;5014.774335155;5033.823816166417;5035.823746168867;4809.038192361471;4848.369711937426;4849.151398505177;4889.486603906538;4948.925766113583 12:37:17 INFO - PROCESS | 28522 | |1;ext.html;548.6653141583636;596.964182149071;605.6880706436186;607.3440684061214;611.513846492039;28293.434131317386;28776.85611571942;30812.84593577029;30902.53646195309;30903.69096309039;41017.79491102548;42081.579184208145;42619.14761704772;42665;42696.786516067456;7599.810004749897;7955.24425179607;7975.60121993898;7976.800579985518;7982.361411087099;7528.247175282483;7714.459987800856;7810.1408845027;7929.405294602899;7941;9919.950400247988;10216.74458138545;10244.846327305097;10572.629957951473;10584.576616935314;10142.340747851404;10227.437490937995;10227.590896364136;10229;10236.078752912199;10111.140553052926;10212.387256764543;10217.335873168295;10219.642312519101;10220.693379198636;38330.80834595837;38703.80648096742;38714.41928371077;38752.418713719315;38789.22421551553;2377.5244951009868;2475.3687809608437;2627.5795872660283;3150.070729134911;3180.904572862818;27385.863070684718;28153.29616759577;30799.692003079905;30870.22824429385;30938.53592196119;3396.4056290149374;3481.704055155315;3522.3824258347718;3553.4492153716214;3566.7503274770775;259067;259147.9999999981;259229;259385;260316;55219;55582.16626750602;55630.16554751682;55981.440185597625;56187.157192642146;59162.70418647879;59224.703876480344;59231.703841480514;59359;59390.703046484494;15128.773068403983;15476.380944762084;15491.845081549265;15500.379984800707;15502.9224853875;11612.709682258012;11670.124740644485;11746;11751.236169648946;11764.529418823151;16622.584435389213;17614.647707045788;17618;17693.734593981102;17891.821081789014;6353.396427339396;6611.338866113447;6641.1774995626665;6657.167854018247;6663.666816659148;860.8579584368634;966.9469123288266;1009.8131845608586;1022.6574097677212;1037.5642230263309;29040.273993149916;29197.124086277447;29282.707172928425;29705.25736856596;29761.25596860096;988;993.8558908958161;998.8152191844387;1036.5698235232294;1039.594558122333;15227.619309517351;15327.31027103783;15377.692446151239;15588.92205538965;15630.29663665115;381670;384226.2618990474;385684;386228;386297;51713.482865171616;51784.74107629438;51804.48195517997;51953.7402312986;52056.739716301934;1084.6041194963782;1179.5222934711455;1196.2642974570576;1205.2828567002618;1231.993840030812;29088.27279318034;30135.547966780956;30239;30311.848440757654;30774.84612576923;43438;43662.56337436585;43963.78018110017;44138.55861441473;44244.778776105915;308834;308923;310316;310605;310702;32856.50715239321;34096;38076.23847522979;39126.2174756509;39139.41290880696;43289.3506597395;43992.34011489894;44222.336664949406;44613.330800038675;44732;37260.441093384165;37263.25473490569;37515;37575.43636845395;37848.81075594604;36665.81667091647;37105.44341834821;37285.4407183887;37536.624633753316;37763.43354849734;36390;37003.44494832526;37086.44370334393;37144.25711485809;37803;33180.33639327248;33219.833900830345;34269.314613708084;34487.31025379529;34502.65497344994;25233.621495677213;26157.476850463267;26169.73830261673;26222.213333600022;26569 12:37:17 INFO - PROCESS | 28522 | |2;jquery.html;358.08330673475905;359.78412952228666;360.5709206044806;369.49010365695335;376.7795839433931;15786.289616967235;16244.593885152863;16844.74732879008;16853.747193792104;16873.156342182887;14206.502772402968;15129.77305340418;15155.166465844373;15158.924205378986;15176.241187940614;4770.260609605508;5202.661826981253;5205.115130427817;5206;5206.609504287175;3591.6947059499976;3756.4740936268945;5084.4661310562415;5712.514436272922;6000.459958603725;5997.790077347294;6026.608270462428;6030.306514750794;6038.215032045828;6052.243469566304;5838.620489668179;6093.69531523422;6105.8168254952425;6106.2061931948565;6107.786227482061;4395.78021098947;5292.364916210041;5292.391374991887;5292.708901010443;5302.522772950414;11442.427878606037;11905.16663833532;11990.340531270864;11992.580259690956;12011.099167562468;1753.1321995612254;1831.09360866372;1857.5913299074182;2030.664940284866;2350.6003979323555;9056.230220431205;9108.59011344491;12066.69833254167;12543.184692994926;12682.936585317108;1608.8632466240383;1665.3255431550242;1679.3786299069423;1740.1038465190436;1763.4004438490974;69097.65451172763;71275;71720.6413967932;71752.28247717509;72567;9738.805223895482;9841.852372214422;10099.242556808269;10160.441175735405;10837.133029357632;13988.370523326475;14015.299235038412;14073.929630351782;14150.29248537569;14160.150390976574;7205.89191162133;7262.745803896838;7278.96360518194;7423.1092268927;7457.142428620669;6915.101036865175;6969.2682268361095;6997.195322537871;7007.948807678889;7053.047838541834;4253.3194688849635;4258.254805409041;4287.164003019381;4340.826366945287;4342.869713908588;1663.2931004323357;1699.991500042492;1755.8946463212249;1821.3989383503413;1843.4469659102215;826.3802148388709;832.9916700832914;883.2536506651869;926.2821313482106;931.487681775021;15726.528204153894;15887.443939462064;16185.676286474205;16383.59041023984;16629.833701663072;869.5478351257311;911.8404279251105;916.8074704312036;921.7465197070859;927.3647551427293;8699.507340084778;9100.271978241724;9111.772205694911;9239.353245272767;9252.629894804268;284653;286915;287629;386842;406975;80358;85319;85768;86033;86598.13401865901;950.5769932380163;975.4050029482011;993.9254555908336;995.929375920888;996.1134590214715;15148.621284467536;15243.314050867959;15449.304781285095;15490.225488725744;15772.526824195293;35254.64745352514;35419.468707969914;37228.62771372252;37996.44042714892;38168;45985.540144598126;48240.03519929652;50124.49875501344;52163.217551737514;53366.19950700665;6979.057827193264;7071.752488662872;7087.043249161504;7087.82280442993;7090.326418990187;5811.535077193899;9586.376885502421;10396.272260941661;10420.739481513023;10422.21833362516;9253.398529095588;9253.907460925573;9525.380850244712;9616.471094089893;9699.321047526606;9652.565634546321;9688.75778105553;9731.41611503312;9766.462844543334;9801.705948821547;5626.493615574618;9217.631294748404;9517.381370210915;9575.904240957501;9938.45338506389;7042.401395881203;7042.7887163385185;7102.786916392516;7171.713131474891;7198.8920166198595;7161.4270858330165;9297.767555811159;9483.478408687588;9515.904840951778;9562.378445401027 12:37:17 INFO - PROCESS | 28522 | |3;mootools.html;1283.2492991599913;1413.9858601413987;1443.7834324851272;1538.5615099696588;1780.688379533582;6615.20617525897;7576.204498527658;7748.806279843;7754;7988.92011079889;7739.187385324544;7795.3763698904095;7853.5680537570415;7903.446758726877;7912.8813067803885;1912.0630890863447;2161.9567608647817;2172.5220451500686;2200.020990659157;2223.0552015393473;2228.765979572146;2255.2670382125793;2256.2216035467736;2256.6727824465443;2258.3450799268194;2191;2198.7801219878043;2240.9663855042186;2242.2264318810066;2245.7305123385136;1819.5087326421858;2076.086521930346;2127.851050426471;2128.478522761933;2149.301477019967;2119.459537817862;2130.1905275995023;2134.871907685528;2138.3264271754347;2140.5825863956534;7724.382049436088;7762;7806.6096695165015;7816.68733250675;7825.960870195612;4509.1883460977015;4593.701409408378;4601.470830854461;4610.262358022734;4620.907581848378;2806.649168853893;2879.611252480909;2991.491446454108;3019.2602812311125;3035.5446682997726;920.8618707193907;943.2029934705163;947.6020071569957;955.1260596554145;968.3802366485455;10533.894661053368;10585.576576936914;10590.364578125336;10606.204534659932;10628.787424251472;4060.3909413587608;4153.979230103831;4242.109157077049;4258.957410425918;4260.723053001545;8924.776880577907;8933.330000250007;8945.15021072997;8965.193132617966;8969.430349688779;4689.695169813951;4691.507391723888;4693.882652933704;4701.412323459567;4710.599599034121;3736.925261494809;4124.649404800565;4243.618074373259;4250.53244143144;4319.460067491564;3655.3420384331075;3667.5782285037562;3742.1954279829424;3772.0569857535615;3795.9430608540893;1181.6927598824195;1204.0668481926577;1204.753025629744;1207.824865394513;1218.104693050607;748.7416841189781;845.1671668981141;864.9956750216209;873.2315562305258;887.3078998381272;5218.112920803396;5452.672839629556;5485.533729633026;5510.862228444321;5556.305461817273;394.9269385163754;399.3351070467639;400.90779120801795;403.9091204478969;409.4513352108189;3613.223157021253;3672.3573374659327;3706.129059670975;3765.4163604641326;3792.393217085253;9216.95391523038;9406.43561386319;9551.379160354554;9582.472963986907;9584.28117891161;9402.106799854002;9444.95277523608;9472.479013654178;9480.146786789084;9494.098060684362;7217.530860494051;7249.456290778213;7275.745348912763;7302.598357090411;7309.634518274065;5317.8404647860625;5426;5509.834704958857;5581.6371935824045;5736.741846616996;8366.790830229293;8673.783155421164;8685.8697119542;8722.65109395617;8856.557172141496;8723.171298726611;8969.14793094655;8970.910290897007;9089.272858171462;9111.863322050041;6104.084387341934;6482.675866206765;6590.80227593173;6695.7321707133115;6754.155730533684;7315;7318.817029574303;7385.926140738524;7397.112346518452;7405.44459165554;6407.2631647359285;6631.767888123893;6717.630530320879;6720.495962802712;6815.591064535945;6709.060731497691;6711.261761206361;6718.798436046926;6756.155480564929;6782.830429239309;6357.396047375492;6407.967960160169;6453.903191452038;6459.515536334699;6467.708953097216;4610.561996610317;4753.358296629911;4781.904361912672;4810.807567697253;4838.129136755418;4693.483716791082;4700.858974230779;4719.079779443113;4757.333973243818;4778 12:37:17 INFO - PROCESS | 28522 | |4;prototype.html;288.37710545222313;297.47495670142195;302.06661416223864;303.1647810282671;303.62047440699126;4682.601978831794;4823.372961515007;4850.781714822832;4906.901861962759;4924.3105965164905;4603.838865639704;4733.8579842604695;4743.573078422941;4748.620110391169;4805.735684537349;3768.3782175941005;3781.621837816224;3787.886363409102;3797.1646237827636;3823.7323387362894;3607;3626.4016437287814;3722.5719042310075;3807.733458657895;3839.308924393608;3698.7780733155964;3708.683417386829;3713.1459764254237;3729.105014796443;3731.589525152231;3718.2935242303874;3733.943990840126;3741.9064523387133;3748.282495190992;3759.812009399519;3326.76712630116;3340.198352395432;3341.816200108993;3350.832458377096;3353.161709572607;4448.065906159711;4450.888727781831;4497.955020449786;4520.886977825548;4529.7735113244535;4216.198922204771;4229.577042295777;4243.665983708947;4248.299030659938;4255.276602977501;4775.2598347256235;4912.70523768575;4935.3090567320605;4939.53074457926;4941.45643979162;3497.387957107496;3498.4402495600834;3501.6673416025437;3520.119970007498;3527.7001454876367;5220.425753167148;5237.79048838046;5238.73806309683;5265.76304066318;5276.1030624793875;3582.1044738815294;3583.7670551414076;3599.7120230381506;3603.171270607775;3616.168281295264;3512.332656795201;3648.7081033517256;3651.507046548682;3659.5544759820255;3660.810236673092;4134.84987806339;4151.3772934059525;4171.228322760299;4178.770167640809;4185.602367775056;4954.455009948902;4960.727160006162;4965.577925876269;4980.327655766498;4998.325226094431;1157.0338767129542;1165.7493638867684;1186.258588382261;1194.319238034312;1205.7467931734259;798.2655956520016;803.2329125684956;817.8323443694028;827.5683068291888;832.8292699996487;4014.397840323975;4017.9196416071513;4039.0104424415617;4045.2516284487465;4050.8987275317763;4617.907641847211;4631.930521042187;4649.209634362167;4654.066689309276;4655.674102812838;4060.355555999825;4073.898152546151;4076.429299898017;4085.673146148301;4096.446979657708;2371.679823223877;2454.558179527702;2464.8521088734733;2478.8016958643625;2484.5403600334;4352.608265256142;4366.585174408426;4382.956170438319;4383.693141480129;4427.977860110744;4680.765961701902;4684.297355396649;4693.178693728584;4700.318453824245;4731.597814185764;4176.4570605821655;4340.8697739067875;4367.2575662137515;4374.19077470669;4389.275769498125;4537.909241815211;4550.522195169459;4557.270836666117;4593.28804035375;4601.12578610063;4468.575485328888;4480.39514665516;4504.144212599595;4508.391367165391;4548.20406428867;4104.7834169549305;4357.912841743084;4372.584604462571;4401;4454.374153434038;3275.412424184867;3325.9667403325657;3329.35077659854;3333.8166400848186;3336.3327334532705;4545.613622841963;4569.657275704269;4582.725036497821;4624.479658586011;4624.820670728943;4818.879528011687;4874.073925954199;4898.559129678346;4907.214845624683;4909.1654418748185;4436.600705936481;4857.878553036202;4905.8037678492465;4910.852674419773;4924.734622073886;4799.544043315879;4818.204996175592;4882.951170488392;4884.853454396374;4941.258811178352;5042.495750424928;5043.142665746906;5064.164412871834;5065.645404821627;5070.290159377616;4851.151048566558;4917.877053073558;4919.262110683283;4928.728919909439;4940.7035577865445 12:37:17 INFO - PROCESS | 28522 | |5;yui.html;582.1355287398213;594.5600255810699;601.1493736363046;621.8600814816665;657.5298661457059;10914.890851091497;10918.836217456725;11043.171762117832;11093.278936869097;11339.26294790839;12371.195872268296;12412.875871241285;12417.875821241785;12425.937870310636;12434.82390581649;628.6573817269576;636.4335741190345;636.9617822930616;777.2693667952107;793.5913004802532;642.1844257792601;644.2397970394956;767.3745897093878;772.2200577416793;780.0366547314063;824.905135909369;831.9750407487754;833.3791325462508;834.8038211020405;835.1189495082685;818;822.0874828939872;826.4586695714316;826.7519744076753;826.8428998490267;484.13581756564713;591.0159584292148;595.6634501506678;596.0790578556141;609.6098496961948;12710.300933448656;12745.745085098339;12747.808782868264;12772.042096842773;12790.360481975957;10811.189160812892;10869.510872010778;10896.727581810439;11101.944490277498;11882.22765520247;10675.78648427035;10763.13894888407;11049.944750276278;11114.61098861544;11294.096472282197;3746.138388170736;3764.228333191689;3782.6326527572987;3819.3507103792144;3835.8082095895093;44351.11297774059;44912.10175796466;44957.775211123735;44991.32513012275;45087.32369014467;8991.460512369278;9207.861882071775;9244.075592440837;9301.953490232505;9368.718938431857;15123.092614443169;15213;15243.542693719206;15248.313825877862;15286.847131528542;6147.170132032208;6277.24673039229;6291.339409362043;6388.840278993062;6506.31683673217;3370.1406141433777;3393.609734880471;3483.8432270547887;3505.018594793462;3505.4391297392294;2548.362909272682;2602.869856507205;2604.4530648564014;2617.1232637066796;2623.553995820715;477.9485132707987;479.94960529144635;481.11234771845886;483.66143699410554;494.6018455143644;526.2816255810791;560.6131769079323;561.9269494965629;570.4837122404231;571.5827445964469;7276.235995220532;7324.2309557496765;7470.813229669302;7568.470207085451;7580.54516728998;500.059887411671;526.2711145064113;551.8179000929683;564.5370795947284;579.756502269048;3393.2195595013286;3413.74396920232;3421.2473255883665;3550.786952782841;3601.9279614408088;47313.290300645516;48838.5116148834;48885.75557122263;54658.45341546613;54727;48025;48026.27960580594;48954;48986.51013489819;49610.255846162334;45783.313250301275;45785;45895;46305.768471157426;46381;8214.26071653554;8269.669213231404;8270.958645206976;8403.873941890999;8456.704015359433;31119;31281.374372512877;31284.843575781975;31687.366252675278;31848.840755796075;39660.40509392304;40707.59292407156;40925;40940.59059409368;41116.1776764469;10314.278000540191;10397.48012599352;10609.681709548726;10629.415382154055;10833.133349331876;11645.301281922773;11670.358130302915;11712.472938717608;11773.05815534738;12208.572699955459;10484.842727359248;10676.412797296223;10999.065079468335;11342.886571134513;11368;9858.334370720777;9909.702708918743;10319.896801032193;10398.584056637952;10587.100096491577;10328.793424131625;10650.520726567478;10690.358578485315;10861.185411094042;10911.345319280868;8762.167594078552;8777.912220877965;8923.643054277756;9180.127887850911;9660.661876834278;9289;9445.716628501155;9655.372400793925;9739.220862331138;9821.901780982385 12:37:17 INFO - PROCESS | 28522 | __end_tp_report 12:37:17 INFO - PROCESS | 28522 | __start_cc_report 12:37:17 INFO - PROCESS | 28522 | _x_x_mozilla_cycle_collect,1062 12:37:17 INFO - PROCESS | 28522 | __end_cc_report 12:37:17 INFO - PROCESS | 28522 | __startTimestamp1464637037120__endTimestamp 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | ------- Summary: start ------- 12:37:17 INFO - PROCESS | 28522 | Number of tests: 6 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#0] dojo.html Cycles:165 Average:4687.66 Median:4838.76 stddev:486.77 (10.1%) stddev-sans-first:461.54 12:37:17 INFO - PROCESS | 28522 | Values: 2659.7 2680.7 2692.7 2700.1 2705.4 4619.2 4666.2 4709.7 4749.3 4791.8 4633.8 4670.1 4676.2 4701.9 4724.4 4264.9 4338.4 4376.1 4390.2 4512.5 4436.3 4450.5 4460.3 4490.4 4511.3 4217.1 4271.9 4313.8 4364.2 4398.3 4331.4 4348.5 4366.9 4442.4 4447.3 4371.9 4385.7 4429.4 4430.3 4436.0 4498.0 4561.7 4638.7 4642.5 4734.7 5153.6 5171.4 5172.7 5174.9 5174.9 5140.7 5155.9 5157.0 5169.8 5173.5 4627.7 4700.4 4738.3 4772.1 4809.0 5278.0 5285.2 5291.2 5291.4 5292.4 5047.3 5052.8 5054.6 5057.6 5061.9 4870.1 4904.3 4913.4 4953.2 4993.2 4881.5 4901.4 4901.6 4903.1 4949.0 4886.3 4890.6 4892.9 4893.2 4939.2 4208.0 4211.6 4219.2 4227.7 4262.8 3714.3 3721.6 3725.9 3756.3 3759.9 4375.0 4377.8 4383.5 4412.7 4423.3 4932.4 5042.0 5066.0 5072.5 5093.5 4385.3 4403.1 4439.3 4450.2 4455.7 4633.1 4641.7 4646.9 4647.1 4695.1 4727.2 4780.6 4813.5 4884.8 4901.9 5105.1 5112.6 5117.5 5117.5 5151.3 4808.3 4847.8 4848.1 4849.9 4874.2 4838.8 4926.3 4942.9 4979.6 5065.4 5089.3 5111.8 5118.7 5122.3 5153.5 4934.3 4937.4 4942.3 4944.3 4944.5 4796.5 4860.8 4914.2 4922.2 4928.5 4832.7 4842.7 4924.3 4925.6 4931.7 4877.5 4982.6 5014.8 5033.8 5035.8 4809.0 4848.4 4849.2 4889.5 4948.9 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#1] ext.html Cycles:180 Average:48349.00 Median:34494.98 stddev:84571.19 (245.2%) stddev-sans-first:84732.28 12:37:17 INFO - PROCESS | 28522 | Values: 548.7 597.0 605.7 607.3 611.5 28293.4 28776.9 30812.8 30902.5 30903.7 41017.8 42081.6 42619.1 42665.0 42696.8 7599.8 7955.2 7975.6 7976.8 7982.4 7528.2 7714.5 7810.1 7929.4 7941.0 9920.0 10216.7 10244.8 10572.6 10584.6 10142.3 10227.4 10227.6 10229.0 10236.1 10111.1 10212.4 10217.3 10219.6 10220.7 38330.8 38703.8 38714.4 38752.4 38789.2 2377.5 2475.4 2627.6 3150.1 3180.9 27385.9 28153.3 30799.7 30870.2 30938.5 3396.4 3481.7 3522.4 3553.4 3566.8 259067.0 259148.0 259229.0 259385.0 260316.0 55219.0 55582.2 55630.2 55981.4 56187.2 59162.7 59224.7 59231.7 59359.0 59390.7 15128.8 15476.4 15491.8 15500.4 15502.9 11612.7 11670.1 11746.0 11751.2 11764.5 16622.6 17614.6 17618.0 17693.7 17891.8 6353.4 6611.3 6641.2 6657.2 6663.7 860.9 966.9 1009.8 1022.7 1037.6 29040.3 29197.1 29282.7 29705.3 29761.3 988.0 993.9 998.8 1036.6 1039.6 15227.6 15327.3 15377.7 15588.9 15630.3 381670.0 384226.3 385684.0 386228.0 386297.0 51713.5 51784.7 51804.5 51953.7 52056.7 1084.6 1179.5 1196.3 1205.3 1232.0 29088.3 30135.5 30239.0 30311.8 30774.8 43438.0 43662.6 43963.8 44138.6 44244.8 308834.0 308923.0 310316.0 310605.0 310702.0 32856.5 34096.0 38076.2 39126.2 39139.4 43289.4 43992.3 44222.3 44613.3 44732.0 37260.4 37263.3 37515.0 37575.4 37848.8 36665.8 37105.4 37285.4 37536.6 37763.4 36390.0 37003.4 37086.4 37144.3 37803.0 33180.3 33219.8 34269.3 34487.3 34502.7 25233.6 26157.5 26169.7 26222.2 26569.0 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#2] jquery.html Cycles:180 Average:22433.18 Median:29334.36 stddev:56123.11 (191.3%) stddev-sans-first:56256.07 12:37:17 INFO - PROCESS | 28522 | Values: 358.1 359.8 360.6 369.5 376.8 15786.3 16244.6 16844.7 16853.7 16873.2 14206.5 15129.8 15155.2 15158.9 15176.2 4770.3 5202.7 5205.1 5206.0 5206.6 3591.7 3756.5 5084.5 5712.5 6000.5 5997.8 6026.6 6030.3 6038.2 6052.2 5838.6 6093.7 6105.8 6106.2 6107.8 4395.8 5292.4 5292.4 5292.7 5302.5 11442.4 11905.2 11990.3 11992.6 12011.1 1753.1 1831.1 1857.6 2030.7 2350.6 9056.2 9108.6 12066.7 12543.2 12682.9 1608.9 1665.3 1679.4 1740.1 1763.4 69097.7 71275.0 71720.6 71752.3 72567.0 9738.8 9841.9 10099.2 10160.4 10837.1 13988.4 14015.3 14073.9 14150.3 14160.2 7205.9 7262.7 7279.0 7423.1 7457.1 6915.1 6969.3 6997.2 7007.9 7053.0 4253.3 4258.3 4287.2 4340.8 4342.9 1663.3 1700.0 1755.9 1821.4 1843.4 826.4 833.0 883.3 926.3 931.5 15726.5 15887.4 16185.7 16383.6 16629.8 869.5 911.8 916.8 921.7 927.4 8699.5 9100.3 9111.8 9239.4 9252.6 284653.0 286915.0 287629.0 386842.0 406975.0 80358.0 85319.0 85768.0 86033.0 86598.1 950.6 975.4 993.9 995.9 996.1 15148.6 15243.3 15449.3 15490.2 15772.5 35254.6 35419.5 37228.6 37996.4 38168.0 45985.5 48240.0 50124.5 52163.2 53366.2 6979.1 7071.8 7087.0 7087.8 7090.3 5811.5 9586.4 10396.3 10420.7 10422.2 9253.4 9253.9 9525.4 9616.5 9699.3 9652.6 9688.8 9731.4 9766.5 9801.7 5626.5 9217.6 9517.4 9575.9 9938.5 7042.4 7042.8 7102.8 7171.7 7198.9 7161.4 9297.8 9483.5 9515.9 9562.4 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#3] mootools.html Cycles:180 Average:5121.72 Median:5267.98 stddev:2849.50 (54.1%) stddev-sans-first:2842.89 12:37:17 INFO - PROCESS | 28522 | Values: 1283.2 1414.0 1443.8 1538.6 1780.7 6615.2 7576.2 7748.8 7754.0 7988.9 7739.2 7795.4 7853.6 7903.4 7912.9 1912.1 2162.0 2172.5 2200.0 2223.1 2228.8 2255.3 2256.2 2256.7 2258.3 2191.0 2198.8 2241.0 2242.2 2245.7 1819.5 2076.1 2127.9 2128.5 2149.3 2119.5 2130.2 2134.9 2138.3 2140.6 7724.4 7762.0 7806.6 7816.7 7826.0 4509.2 4593.7 4601.5 4610.3 4620.9 2806.6 2879.6 2991.5 3019.3 3035.5 920.9 943.2 947.6 955.1 968.4 10533.9 10585.6 10590.4 10606.2 10628.8 4060.4 4154.0 4242.1 4259.0 4260.7 8924.8 8933.3 8945.2 8965.2 8969.4 4689.7 4691.5 4693.9 4701.4 4710.6 3736.9 4124.6 4243.6 4250.5 4319.5 3655.3 3667.6 3742.2 3772.1 3795.9 1181.7 1204.1 1204.8 1207.8 1218.1 748.7 845.2 865.0 873.2 887.3 5218.1 5452.7 5485.5 5510.9 5556.3 394.9 399.3 400.9 403.9 409.5 3613.2 3672.4 3706.1 3765.4 3792.4 9217.0 9406.4 9551.4 9582.5 9584.3 9402.1 9445.0 9472.5 9480.1 9494.1 7217.5 7249.5 7275.7 7302.6 7309.6 5317.8 5426.0 5509.8 5581.6 5736.7 8366.8 8673.8 8685.9 8722.7 8856.6 8723.2 8969.1 8970.9 9089.3 9111.9 6104.1 6482.7 6590.8 6695.7 6754.2 7315.0 7318.8 7385.9 7397.1 7405.4 6407.3 6631.8 6717.6 6720.5 6815.6 6709.1 6711.3 6718.8 6756.2 6782.8 6357.4 6408.0 6453.9 6459.5 6467.7 4610.6 4753.4 4781.9 4810.8 4838.1 4693.5 4700.9 4719.1 4757.3 4778.0 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#4] prototype.html Cycles:180 Average:3982.62 Median:4386.48 stddev:1147.78 (26.2%) stddev-sans-first:1117.00 12:37:17 INFO - PROCESS | 28522 | Values: 288.4 297.5 302.1 303.2 303.6 4682.6 4823.4 4850.8 4906.9 4924.3 4603.8 4733.9 4743.6 4748.6 4805.7 3768.4 3781.6 3787.9 3797.2 3823.7 3607.0 3626.4 3722.6 3807.7 3839.3 3698.8 3708.7 3713.1 3729.1 3731.6 3718.3 3733.9 3741.9 3748.3 3759.8 3326.8 3340.2 3341.8 3350.8 3353.2 4448.1 4450.9 4498.0 4520.9 4529.8 4216.2 4229.6 4243.7 4248.3 4255.3 4775.3 4912.7 4935.3 4939.5 4941.5 3497.4 3498.4 3501.7 3520.1 3527.7 5220.4 5237.8 5238.7 5265.8 5276.1 3582.1 3583.8 3599.7 3603.2 3616.2 3512.3 3648.7 3651.5 3659.6 3660.8 4134.8 4151.4 4171.2 4178.8 4185.6 4954.5 4960.7 4965.6 4980.3 4998.3 1157.0 1165.7 1186.3 1194.3 1205.7 798.3 803.2 817.8 827.6 832.8 4014.4 4017.9 4039.0 4045.3 4050.9 4617.9 4631.9 4649.2 4654.1 4655.7 4060.4 4073.9 4076.4 4085.7 4096.4 2371.7 2454.6 2464.9 2478.8 2484.5 4352.6 4366.6 4383.0 4383.7 4428.0 4680.8 4684.3 4693.2 4700.3 4731.6 4176.5 4340.9 4367.3 4374.2 4389.3 4537.9 4550.5 4557.3 4593.3 4601.1 4468.6 4480.4 4504.1 4508.4 4548.2 4104.8 4357.9 4372.6 4401.0 4454.4 3275.4 3326.0 3329.4 3333.8 3336.3 4545.6 4569.7 4582.7 4624.5 4624.8 4818.9 4874.1 4898.6 4907.2 4909.2 4436.6 4857.9 4905.8 4910.9 4924.7 4799.5 4818.2 4883.0 4884.9 4941.3 5042.5 5043.1 5064.2 5065.6 5070.3 4851.2 4917.9 4919.3 4928.7 4940.7 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - PROCESS | 28522 | [#5] yui.html Cycles:180 Average:12764.24 Median:46100.38 stddev:14817.92 (32.1%) stddev-sans-first:14831.24 12:37:17 INFO - PROCESS | 28522 | Values: 582.1 594.6 601.1 621.9 657.5 10914.9 10918.8 11043.2 11093.3 11339.3 12371.2 12412.9 12417.9 12425.9 12434.8 628.7 636.4 637.0 777.3 793.6 642.2 644.2 767.4 772.2 780.0 824.9 832.0 833.4 834.8 835.1 818.0 822.1 826.5 826.8 826.8 484.1 591.0 595.7 596.1 609.6 12710.3 12745.7 12747.8 12772.0 12790.4 10811.2 10869.5 10896.7 11101.9 11882.2 10675.8 10763.1 11049.9 11114.6 11294.1 3746.1 3764.2 3782.6 3819.4 3835.8 44351.1 44912.1 44957.8 44991.3 45087.3 8991.5 9207.9 9244.1 9302.0 9368.7 15123.1 15213.0 15243.5 15248.3 15286.8 6147.2 6277.2 6291.3 6388.8 6506.3 3370.1 3393.6 3483.8 3505.0 3505.4 2548.4 2602.9 2604.5 2617.1 2623.6 477.9 479.9 481.1 483.7 494.6 526.3 560.6 561.9 570.5 571.6 7276.2 7324.2 7470.8 7568.5 7580.5 500.1 526.3 551.8 564.5 579.8 3393.2 3413.7 3421.2 3550.8 3601.9 47313.3 48838.5 48885.8 54658.5 54727.0 48025.0 48026.3 48954.0 48986.5 49610.3 45783.3 45785.0 45895.0 46305.8 46381.0 8214.3 8269.7 8271.0 8403.9 8456.7 31119.0 31281.4 31284.8 31687.4 31848.8 39660.4 40707.6 40925.0 40940.6 41116.2 10314.3 10397.5 10609.7 10629.4 10833.1 11645.3 11670.4 11712.5 11773.1 12208.6 10484.8 10676.4 10999.1 11342.9 11368.0 9858.3 9909.7 10319.9 10398.6 10587.1 10328.8 10650.5 10690.4 10861.2 10911.3 8762.2 8777.9 8923.6 9180.1 9660.7 9289.0 9445.7 9655.4 9739.2 9821.9 12:37:17 INFO - PROCESS | 28522 | -------- Summary: end -------- 12:37:17 INFO - PROCESS | 28522 | 12:37:17 INFO - TEST-INFO | 28522: exit 0 12:37:17 INFO - TEST-OK | dromaeo_css | took 1118957ms 12:37:17 INFO - TEST-START | kraken 12:37:17 INFO - operating with platform_type : linux_ 12:37:17 INFO - Initialising browser for kraken test... 12:37:17 INFO - TEST-INFO | started process 28708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_bVee/profile http://localhost:43905/getInfo.html) 12:37:22 INFO - PROCESS | 28708 | __metrics Screen width/height:1600/1200 12:37:22 INFO - PROCESS | 28708 | colorDepth:24 12:37:22 INFO - PROCESS | 28708 | Browser inner width/height: 1024/697 12:37:22 INFO - PROCESS | 28708 | __metrics 12:37:23 ERROR - PROCESS | 28708 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:37:24 INFO - TEST-INFO | 28708: exit 0 12:37:24 INFO - Browser initialized. 12:37:24 INFO - Running cycle 1/1 for kraken test... 12:37:24 INFO - TEST-INFO | started process 28788 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV_bVee/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:36 INFO - PROCESS | 28788 | RSS: Main: 157032448 12:37:36 INFO - PROCESS | 28788 | 12:37:38 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43905/tests/kraken/driver.html?testName=audio-beat-detection) 12:37:39 INFO - PROCESS | 28788 | RSS: Main: 212934656 12:37:39 INFO - PROCESS | 28788 | 12:37:42 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43905/tests/kraken/driver.html?testName=audio-dft) 12:37:43 INFO - PROCESS | 28788 | RSS: Main: 200179712 12:37:43 INFO - PROCESS | 28788 | 12:37:46 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43905/tests/kraken/driver.html?testName=audio-fft) 12:37:47 INFO - PROCESS | 28788 | RSS: Main: 269733888 12:37:47 INFO - PROCESS | 28788 | 12:37:49 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43905/tests/kraken/driver.html?testName=audio-oscillator) 12:37:49 INFO - PROCESS | 28788 | RSS: Main: 178720768 12:37:49 INFO - PROCESS | 28788 | 12:37:52 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43905/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:37:52 INFO - PROCESS | 28788 | RSS: Main: 181743616 12:37:52 INFO - PROCESS | 28788 | 12:37:55 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43905/tests/kraken/driver.html?testName=imaging-darkroom) 12:37:56 INFO - PROCESS | 28788 | RSS: Main: 218595328 12:37:56 INFO - PROCESS | 28788 | 12:38:00 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43905/tests/kraken/driver.html?testName=imaging-desaturate) 12:38:00 INFO - PROCESS | 28788 | RSS: Main: 219664384 12:38:00 INFO - PROCESS | 28788 | 12:38:03 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43905/tests/kraken/driver.html?testName=json-parse-financial) 12:38:04 INFO - PROCESS | 28788 | RSS: Main: 220377088 12:38:04 INFO - PROCESS | 28788 | 12:38:05 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43905/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:38:06 INFO - PROCESS | 28788 | RSS: Main: 209879040 12:38:06 INFO - PROCESS | 28788 | 12:38:08 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:38:09 INFO - PROCESS | 28788 | RSS: Main: 211283968 12:38:09 INFO - PROCESS | 28788 | 12:38:11 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:38:11 INFO - PROCESS | 28788 | RSS: Main: 222736384 12:38:11 INFO - PROCESS | 28788 | 12:38:14 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:38:15 INFO - PROCESS | 28788 | RSS: Main: 226615296 12:38:15 INFO - PROCESS | 28788 | 12:38:18 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:38:18 INFO - PROCESS | 28788 | RSS: Main: 222433280 12:38:18 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | Cycle 1(1): loaded http://localhost:43905/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43905/tests/kraken/driver.html?testName=ai-astar) 12:38:20 INFO - PROCESS | 28788 | RSS: Main: 267821056 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | __start_tp_report 12:38:20 INFO - PROCESS | 28788 | _x_x_mozilla_page_load 12:38:20 INFO - PROCESS | 28788 | _x_x_mozilla_page_load_details 12:38:20 INFO - PROCESS | 28788 | |i|pagename|runs| 12:38:20 INFO - PROCESS | 28788 | |0;ai-astar;113;109;114;114;112;115;113;113;112;113 12:38:20 INFO - PROCESS | 28788 | |1;audio-beat-detection;154;129;132;160;129;160;151;140;186;129 12:38:20 INFO - PROCESS | 28788 | |2;audio-dft;161;160;163;162;161;162;160;160;161;160 12:38:20 INFO - PROCESS | 28788 | |3;audio-fft;75;76;75;107;76;77;75;75;97;79 12:38:20 INFO - PROCESS | 28788 | |4;audio-oscillator;89;85;131;84;83;83;121;83;83;135 12:38:20 INFO - PROCESS | 28788 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 12:38:20 INFO - PROCESS | 28788 | |6;imaging-darkroom;146;146;147;145;146;147;146;146;146;145 12:38:20 INFO - PROCESS | 28788 | |7;imaging-desaturate;95;94;95;97;95;95;94;94;94;95 12:38:20 INFO - PROCESS | 28788 | |8;json-parse-financial;64;64;65;63;65;64;65;93;61;61 12:38:20 INFO - PROCESS | 28788 | |9;json-stringify-tinderbox;65;66;65;66;66;66;66;66;66;65 12:38:20 INFO - PROCESS | 28788 | |10;stanford-crypto-aes;83;82;82;83;84;84;85;85;84;84 12:38:20 INFO - PROCESS | 28788 | |11;stanford-crypto-ccm;135;131;142;128;128;130;129;126;173;131 12:38:20 INFO - PROCESS | 28788 | |12;stanford-crypto-pbkdf2;170;172;165;172;167;168;166;166;166;164 12:38:20 INFO - PROCESS | 28788 | |13;stanford-crypto-sha256-iterative;69;70;66;68;65;66;67;73;65;66 12:38:20 INFO - PROCESS | 28788 | __end_tp_report 12:38:20 INFO - PROCESS | 28788 | __start_cc_report 12:38:20 INFO - PROCESS | 28788 | _x_x_mozilla_cycle_collect,1908 12:38:20 INFO - PROCESS | 28788 | __end_cc_report 12:38:20 INFO - PROCESS | 28788 | __startTimestamp1464637100805__endTimestamp 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | ------- Summary: start ------- 12:38:20 INFO - PROCESS | 28788 | Number of tests: 14 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.62 (1.4%) stddev-sans-first:1.72 12:38:20 INFO - PROCESS | 28788 | Values: 113.0 109.0 114.0 114.0 112.0 115.0 113.0 113.0 112.0 113.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#1] audio-beat-detection Cycles:10 Average:147.00 Median:152.50 stddev:18.77 (12.3%) stddev-sans-first:19.73 12:38:20 INFO - PROCESS | 28788 | Values: 154.0 129.0 132.0 160.0 129.0 160.0 151.0 140.0 186.0 129.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 12:38:20 INFO - PROCESS | 28788 | Values: 161.0 160.0 163.0 162.0 161.0 162.0 160.0 160.0 161.0 160.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.28 (14.8%) stddev-sans-first:11.74 12:38:20 INFO - PROCESS | 28788 | Values: 75.0 76.0 75.0 107.0 76.0 77.0 75.0 75.0 97.0 79.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:83.00 stddev:21.94 (26.4%) stddev-sans-first:23.04 12:38:20 INFO - PROCESS | 28788 | Values: 89.0 85.0 131.0 84.0 83.0 83.0 121.0 83.0 83.0 135.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 12:38:20 INFO - PROCESS | 28788 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 12:38:20 INFO - PROCESS | 28788 | Values: 146.0 146.0 147.0 145.0 146.0 147.0 146.0 146.0 146.0 145.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 12:38:20 INFO - PROCESS | 28788 | Values: 95.0 94.0 95.0 97.0 95.0 95.0 94.0 94.0 94.0 95.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:64.50 stddev:9.43 (14.6%) stddev-sans-first:9.96 12:38:20 INFO - PROCESS | 28788 | Values: 64.0 64.0 65.0 63.0 65.0 64.0 65.0 93.0 61.0 61.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 12:38:20 INFO - PROCESS | 28788 | Values: 65.0 66.0 65.0 66.0 66.0 66.0 66.0 66.0 66.0 65.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.07 (1.3%) stddev-sans-first:1.12 12:38:20 INFO - PROCESS | 28788 | Values: 83.0 82.0 82.0 83.0 84.0 84.0 85.0 85.0 84.0 84.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:131.00 stddev:14.00 (10.7%) stddev-sans-first:14.85 12:38:20 INFO - PROCESS | 28788 | Values: 135.0 131.0 142.0 128.0 128.0 130.0 129.0 126.0 173.0 131.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:167.50 stddev:2.84 (1.7%) stddev-sans-first:2.87 12:38:20 INFO - PROCESS | 28788 | Values: 170.0 172.0 165.0 172.0 167.0 168.0 166.0 166.0 166.0 164.0 12:38:20 INFO - PROCESS | 28788 | 12:38:20 INFO - PROCESS | 28788 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:2.55 (3.8%) stddev-sans-first:2.65 12:38:20 INFO - PROCESS | 28788 | Values: 69.0 70.0 66.0 68.0 65.0 66.0 67.0 73.0 65.0 66.0 12:38:20 INFO - PROCESS | 28788 | -------- Summary: end -------- 12:38:20 INFO - PROCESS | 28788 | 12:38:21 INFO - TEST-INFO | 28788: exit 0 12:38:21 INFO - TEST-OK | kraken | took 63946ms 12:38:21 INFO - SUITE-END | took 1182s 12:38:21 INFO - Completed test suite (00:19:42) 12:38:21 INFO - javascript benchmark 12:38:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2659.7473240042195, 2680.745329193726, 2692.69034061083, 2700.095468018215, 2705.3777631144835, 4619.237825758746, 4666.183417901866, 4709.670323077377, 4749.26386410107, 4791.832285869995, 4633.768311584416, 4670.112678591066, 4676.158291507536, 4701.929471057937, 4724.3622111015, 4264.936025959605, 4338.370936214247, 4376.1247750449875, 4390.209762242795, 4512.5261847506035, 4436.334549817521, 4450.51044385119, 4460.264056430685, 4490.393796837434, 4511.255642818947, 4217.135487225111, 4271.893202669927, 4313.84901528445, 4364.170807546556, 4398.252297109483, 4331.350297455407, 4348.5216626171095, 4366.934495982563, 4442.355858400546, 4447.266201076818, 4371.934420983688, 4385.6710746694125, 4429.379886815848, 4430.291153415438, 4436.0240747035605, 4497.9775101124615, 4561.726296422192, 4638.698484598524, 4642.53574642533, 4734.692245004064, 5153.613478989091, 5171.4311425743135, 5172.741362931838, 5174.870628234286, 5174.948250517485, 5140.7172605506685, 5155.871103222449, 5157.0, 5169.767360468751, 5173.534381905609, 4627.745473998929, 4700.435947686299, 4738.3129446230105, 4772.0694464579765, 4809.014152098812, 5278.049951008856, 5285.1543753000105, 5291.206319052096, 5291.391489978625, 5292.364916210079, 5047.318611987408, 5052.8484145475695, 5054.570361519239, 5057.620678449131, 5061.898762024739, 4870.14772414826, 4904.313396124546, 4913.385826771653, 4953.2074868020945, 4993.226049962262, 4881.51184881509, 4901.411830580282, 4901.58336541398, 4903.068417000758, 4949.0, 4886.267059940967, 4890.58430033444, 4892.8532144035735, 4893.168161412497, 4939.234418665113, 4208.032152604918, 4211.642010429086, 4219.177260434186, 4227.661787057028, 4262.808173632194, 3714.257148570243, 3721.5720192177705, 3725.906852328659, 3756.323861704919, 3759.8684046058256, 4374.956250437455, 4377.802998865059, 4383.473983121982, 4412.691111622155, 4423.336499525097, 4932.383452068491, 5042.0, 5066.03745288394, 5072.467390923974, 5093.490650934951, 4385.298352263686, 4403.14138742942, 4439.267520859086, 4450.198964186413, 4455.710378825366, 4633.050224703928, 4641.721496710208, 4646.907061858744, 4647.09381670571, 4695.060987802453, 4727.2436410174205, 4780.569748722632, 4813.470518242921, 4884.780184891617, 4901.926471103007, 5105.132127538253, 5112.616553758408, 5117.513836185556, 5117.5394214520875, 5151.330327057459, 4808.326834243138, 4847.781849816696, 4848.0546293472435, 4849.927251091307, 4874.171390863491, 4838.758062096951, 4926.285688575208, 4942.901141977212, 4979.601631869369, 5065.4174769900455, 5089.338386009943, 5111.821086261963, 5118.74406279692, 5122.282880396673, 5153.45888681698, 4934.333864928189, 4937.388193202232, 4942.332785073969, 4944.283078953604, 4944.505549445027, 4796.5203479653155, 4860.8298709545, 4914.213725803854, 4922.212446008622, 4928.531789479994, 4832.710037397767, 4842.709437433765, 4924.285978533164, 4925.605951523798, 4931.654784165217, 4877.487863774394, 4982.626303027215, 5014.774335155, 5033.823816166417, 5035.823746168867, 4809.038192361471, 4848.369711937426, 4849.151398505177, 4889.486603906538, 4948.925766113583], "value": 4657.100493915486, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.6653141583636, 596.964182149071, 605.6880706436186, 607.3440684061214, 611.513846492039, 28293.434131317386, 28776.85611571942, 30812.84593577029, 30902.53646195309, 30903.69096309039, 41017.79491102548, 42081.579184208145, 42619.14761704772, 42665.0, 42696.786516067456, 7599.810004749897, 7955.24425179607, 7975.60121993898, 7976.800579985518, 7982.361411087099, 7528.247175282483, 7714.459987800856, 7810.1408845027, 7929.405294602899, 7941.0, 9919.950400247988, 10216.74458138545, 10244.846327305097, 10572.629957951473, 10584.576616935314, 10142.340747851404, 10227.437490937995, 10227.590896364136, 10229.0, 10236.078752912199, 10111.140553052926, 10212.387256764543, 10217.335873168295, 10219.642312519101, 10220.693379198636, 38330.80834595837, 38703.80648096742, 38714.41928371077, 38752.418713719315, 38789.22421551553, 2377.5244951009868, 2475.3687809608437, 2627.5795872660283, 3150.070729134911, 3180.904572862818, 27385.863070684718, 28153.29616759577, 30799.692003079905, 30870.22824429385, 30938.53592196119, 3396.4056290149374, 3481.704055155315, 3522.3824258347718, 3553.4492153716214, 3566.7503274770775, 259067.0, 259147.9999999981, 259229.0, 259385.0, 260316.0, 55219.0, 55582.16626750602, 55630.16554751682, 55981.440185597625, 56187.157192642146, 59162.70418647879, 59224.703876480344, 59231.703841480514, 59359.0, 59390.703046484494, 15128.773068403983, 15476.380944762084, 15491.845081549265, 15500.379984800707, 15502.9224853875, 11612.709682258012, 11670.124740644485, 11746.0, 11751.236169648946, 11764.529418823151, 16622.584435389213, 17614.647707045788, 17618.0, 17693.734593981102, 17891.821081789014, 6353.396427339396, 6611.338866113447, 6641.1774995626665, 6657.167854018247, 6663.666816659148, 860.8579584368634, 966.9469123288266, 1009.8131845608586, 1022.6574097677212, 1037.5642230263309, 29040.273993149916, 29197.124086277447, 29282.707172928425, 29705.25736856596, 29761.25596860096, 988.0, 993.8558908958161, 998.8152191844387, 1036.5698235232294, 1039.594558122333, 15227.619309517351, 15327.31027103783, 15377.692446151239, 15588.92205538965, 15630.29663665115, 381670.0, 384226.2618990474, 385684.0, 386228.0, 386297.0, 51713.482865171616, 51784.74107629438, 51804.48195517997, 51953.7402312986, 52056.739716301934, 1084.6041194963782, 1179.5222934711455, 1196.2642974570576, 1205.2828567002618, 1231.993840030812, 29088.27279318034, 30135.547966780956, 30239.0, 30311.848440757654, 30774.84612576923, 43438.0, 43662.56337436585, 43963.78018110017, 44138.55861441473, 44244.778776105915, 308834.0, 308923.0, 310316.0, 310605.0, 310702.0, 32856.50715239321, 34096.0, 38076.23847522979, 39126.2174756509, 39139.41290880696, 43289.3506597395, 43992.34011489894, 44222.336664949406, 44613.330800038675, 44732.0, 37260.441093384165, 37263.25473490569, 37515.0, 37575.43636845395, 37848.81075594604, 36665.81667091647, 37105.44341834821, 37285.4407183887, 37536.624633753316, 37763.43354849734, 36390.0, 37003.44494832526, 37086.44370334393, 37144.25711485809, 37803.0, 33180.33639327248, 33219.833900830345, 34269.314613708084, 34487.31025379529, 34502.65497344994, 25233.621495677213, 26157.476850463267, 26169.73830261673, 26222.213333600022, 26569.0], "value": 18360.63263355095, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.08330673475905, 359.78412952228666, 360.5709206044806, 369.49010365695335, 376.7795839433931, 15786.289616967235, 16244.593885152863, 16844.74732879008, 16853.747193792104, 16873.156342182887, 14206.502772402968, 15129.77305340418, 15155.166465844373, 15158.924205378986, 15176.241187940614, 4770.260609605508, 5202.661826981253, 5205.115130427817, 5206.0, 5206.609504287175, 3591.6947059499976, 3756.4740936268945, 5084.4661310562415, 5712.514436272922, 6000.459958603725, 5997.790077347294, 6026.608270462428, 6030.306514750794, 6038.215032045828, 6052.243469566304, 5838.620489668179, 6093.69531523422, 6105.8168254952425, 6106.2061931948565, 6107.786227482061, 4395.78021098947, 5292.364916210041, 5292.391374991887, 5292.708901010443, 5302.522772950414, 11442.427878606037, 11905.16663833532, 11990.340531270864, 11992.580259690956, 12011.099167562468, 1753.1321995612254, 1831.09360866372, 1857.5913299074182, 2030.664940284866, 2350.6003979323555, 9056.230220431205, 9108.59011344491, 12066.69833254167, 12543.184692994926, 12682.936585317108, 1608.8632466240383, 1665.3255431550242, 1679.3786299069423, 1740.1038465190436, 1763.4004438490974, 69097.65451172763, 71275.0, 71720.6413967932, 71752.28247717509, 72567.0, 9738.805223895482, 9841.852372214422, 10099.242556808269, 10160.441175735405, 10837.133029357632, 13988.370523326475, 14015.299235038412, 14073.929630351782, 14150.29248537569, 14160.150390976574, 7205.89191162133, 7262.745803896838, 7278.96360518194, 7423.1092268927, 7457.142428620669, 6915.101036865175, 6969.2682268361095, 6997.195322537871, 7007.948807678889, 7053.047838541834, 4253.3194688849635, 4258.254805409041, 4287.164003019381, 4340.826366945287, 4342.869713908588, 1663.2931004323357, 1699.991500042492, 1755.8946463212249, 1821.3989383503413, 1843.4469659102215, 826.3802148388709, 832.9916700832914, 883.2536506651869, 926.2821313482106, 931.487681775021, 15726.528204153894, 15887.443939462064, 16185.676286474205, 16383.59041023984, 16629.833701663072, 869.5478351257311, 911.8404279251105, 916.8074704312036, 921.7465197070859, 927.3647551427293, 8699.507340084778, 9100.271978241724, 9111.772205694911, 9239.353245272767, 9252.629894804268, 284653.0, 286915.0, 287629.0, 386842.0, 406975.0, 80358.0, 85319.0, 85768.0, 86033.0, 86598.13401865901, 950.5769932380163, 975.4050029482011, 993.9254555908336, 995.929375920888, 996.1134590214715, 15148.621284467536, 15243.314050867959, 15449.304781285095, 15490.225488725744, 15772.526824195293, 35254.64745352514, 35419.468707969914, 37228.62771372252, 37996.44042714892, 38168.0, 45985.540144598126, 48240.03519929652, 50124.49875501344, 52163.217551737514, 53366.19950700665, 6979.057827193264, 7071.752488662872, 7087.043249161504, 7087.82280442993, 7090.326418990187, 5811.535077193899, 9586.376885502421, 10396.272260941661, 10420.739481513023, 10422.21833362516, 9253.398529095588, 9253.907460925573, 9525.380850244712, 9616.471094089893, 9699.321047526606, 9652.565634546321, 9688.75778105553, 9731.41611503312, 9766.462844543334, 9801.705948821547, 5626.493615574618, 9217.631294748404, 9517.381370210915, 9575.904240957501, 9938.45338506389, 7042.401395881203, 7042.7887163385185, 7102.786916392516, 7171.713131474891, 7198.8920166198595, 7161.4270858330165, 9297.767555811159, 9483.478408687588, 9515.904840951778, 9562.378445401027], "value": 7919.383654141164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1283.2492991599913, 1413.9858601413987, 1443.7834324851272, 1538.5615099696588, 1780.688379533582, 6615.20617525897, 7576.204498527658, 7748.806279843, 7754.0, 7988.92011079889, 7739.187385324544, 7795.3763698904095, 7853.5680537570415, 7903.446758726877, 7912.8813067803885, 1912.0630890863447, 2161.9567608647817, 2172.5220451500686, 2200.020990659157, 2223.0552015393473, 2228.765979572146, 2255.2670382125793, 2256.2216035467736, 2256.6727824465443, 2258.3450799268194, 2191.0, 2198.7801219878043, 2240.9663855042186, 2242.2264318810066, 2245.7305123385136, 1819.5087326421858, 2076.086521930346, 2127.851050426471, 2128.478522761933, 2149.301477019967, 2119.459537817862, 2130.1905275995023, 2134.871907685528, 2138.3264271754347, 2140.5825863956534, 7724.382049436088, 7762.0, 7806.6096695165015, 7816.68733250675, 7825.960870195612, 4509.1883460977015, 4593.701409408378, 4601.470830854461, 4610.262358022734, 4620.907581848378, 2806.649168853893, 2879.611252480909, 2991.491446454108, 3019.2602812311125, 3035.5446682997726, 920.8618707193907, 943.2029934705163, 947.6020071569957, 955.1260596554145, 968.3802366485455, 10533.894661053368, 10585.576576936914, 10590.364578125336, 10606.204534659932, 10628.787424251472, 4060.3909413587608, 4153.979230103831, 4242.109157077049, 4258.957410425918, 4260.723053001545, 8924.776880577907, 8933.330000250007, 8945.15021072997, 8965.193132617966, 8969.430349688779, 4689.695169813951, 4691.507391723888, 4693.882652933704, 4701.412323459567, 4710.599599034121, 3736.925261494809, 4124.649404800565, 4243.618074373259, 4250.53244143144, 4319.460067491564, 3655.3420384331075, 3667.5782285037562, 3742.1954279829424, 3772.0569857535615, 3795.9430608540893, 1181.6927598824195, 1204.0668481926577, 1204.753025629744, 1207.824865394513, 1218.104693050607, 748.7416841189781, 845.1671668981141, 864.9956750216209, 873.2315562305258, 887.3078998381272, 5218.112920803396, 5452.672839629556, 5485.533729633026, 5510.862228444321, 5556.305461817273, 394.9269385163754, 399.3351070467639, 400.90779120801795, 403.9091204478969, 409.4513352108189, 3613.223157021253, 3672.3573374659327, 3706.129059670975, 3765.4163604641326, 3792.393217085253, 9216.95391523038, 9406.43561386319, 9551.379160354554, 9582.472963986907, 9584.28117891161, 9402.106799854002, 9444.95277523608, 9472.479013654178, 9480.146786789084, 9494.098060684362, 7217.530860494051, 7249.456290778213, 7275.745348912763, 7302.598357090411, 7309.634518274065, 5317.8404647860625, 5426.0, 5509.834704958857, 5581.6371935824045, 5736.741846616996, 8366.790830229293, 8673.783155421164, 8685.8697119542, 8722.65109395617, 8856.557172141496, 8723.171298726611, 8969.14793094655, 8970.910290897007, 9089.272858171462, 9111.863322050041, 6104.084387341934, 6482.675866206765, 6590.80227593173, 6695.7321707133115, 6754.155730533684, 7315.0, 7318.817029574303, 7385.926140738524, 7397.112346518452, 7405.44459165554, 6407.2631647359285, 6631.767888123893, 6717.630530320879, 6720.495962802712, 6815.591064535945, 6709.060731497691, 6711.261761206361, 6718.798436046926, 6756.155480564929, 6782.830429239309, 6357.396047375492, 6407.967960160169, 6453.903191452038, 6459.515536334699, 6467.708953097216, 4610.561996610317, 4753.358296629911, 4781.904361912672, 4810.807567697253, 4838.129136755418, 4693.483716791082, 4700.858974230779, 4719.079779443113, 4757.333973243818, 4778.0], "value": 4066.4682195198347, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.37710545222313, 297.47495670142195, 302.06661416223864, 303.1647810282671, 303.62047440699126, 4682.601978831794, 4823.372961515007, 4850.781714822832, 4906.901861962759, 4924.3105965164905, 4603.838865639704, 4733.8579842604695, 4743.573078422941, 4748.620110391169, 4805.735684537349, 3768.3782175941005, 3781.621837816224, 3787.886363409102, 3797.1646237827636, 3823.7323387362894, 3607.0, 3626.4016437287814, 3722.5719042310075, 3807.733458657895, 3839.308924393608, 3698.7780733155964, 3708.683417386829, 3713.1459764254237, 3729.105014796443, 3731.589525152231, 3718.2935242303874, 3733.943990840126, 3741.9064523387133, 3748.282495190992, 3759.812009399519, 3326.76712630116, 3340.198352395432, 3341.816200108993, 3350.832458377096, 3353.161709572607, 4448.065906159711, 4450.888727781831, 4497.955020449786, 4520.886977825548, 4529.7735113244535, 4216.198922204771, 4229.577042295777, 4243.665983708947, 4248.299030659938, 4255.276602977501, 4775.2598347256235, 4912.70523768575, 4935.3090567320605, 4939.53074457926, 4941.45643979162, 3497.387957107496, 3498.4402495600834, 3501.6673416025437, 3520.119970007498, 3527.7001454876367, 5220.425753167148, 5237.79048838046, 5238.73806309683, 5265.76304066318, 5276.1030624793875, 3582.1044738815294, 3583.7670551414076, 3599.7120230381506, 3603.171270607775, 3616.168281295264, 3512.332656795201, 3648.7081033517256, 3651.507046548682, 3659.5544759820255, 3660.810236673092, 4134.84987806339, 4151.3772934059525, 4171.228322760299, 4178.770167640809, 4185.602367775056, 4954.455009948902, 4960.727160006162, 4965.577925876269, 4980.327655766498, 4998.325226094431, 1157.0338767129542, 1165.7493638867684, 1186.258588382261, 1194.319238034312, 1205.7467931734259, 798.2655956520016, 803.2329125684956, 817.8323443694028, 827.5683068291888, 832.8292699996487, 4014.397840323975, 4017.9196416071513, 4039.0104424415617, 4045.2516284487465, 4050.8987275317763, 4617.907641847211, 4631.930521042187, 4649.209634362167, 4654.066689309276, 4655.674102812838, 4060.355555999825, 4073.898152546151, 4076.429299898017, 4085.673146148301, 4096.446979657708, 2371.679823223877, 2454.558179527702, 2464.8521088734733, 2478.8016958643625, 2484.5403600334, 4352.608265256142, 4366.585174408426, 4382.956170438319, 4383.693141480129, 4427.977860110744, 4680.765961701902, 4684.297355396649, 4693.178693728584, 4700.318453824245, 4731.597814185764, 4176.4570605821655, 4340.8697739067875, 4367.2575662137515, 4374.19077470669, 4389.275769498125, 4537.909241815211, 4550.522195169459, 4557.270836666117, 4593.28804035375, 4601.12578610063, 4468.575485328888, 4480.39514665516, 4504.144212599595, 4508.391367165391, 4548.20406428867, 4104.7834169549305, 4357.912841743084, 4372.584604462571, 4401.0, 4454.374153434038, 3275.412424184867, 3325.9667403325657, 3329.35077659854, 3333.8166400848186, 3336.3327334532705, 4545.613622841963, 4569.657275704269, 4582.725036497821, 4624.479658586011, 4624.820670728943, 4818.879528011687, 4874.073925954199, 4898.559129678346, 4907.214845624683, 4909.1654418748185, 4436.600705936481, 4857.878553036202, 4905.8037678492465, 4910.852674419773, 4924.734622073886, 4799.544043315879, 4818.204996175592, 4882.951170488392, 4884.853454396374, 4941.258811178352, 5042.495750424928, 5043.142665746906, 5064.164412871834, 5065.645404821627, 5070.290159377616, 4851.151048566558, 4917.877053073558, 4919.262110683283, 4928.728919909439, 4940.7035577865445], "value": 3619.5976970058723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.1355287398213, 594.5600255810699, 601.1493736363046, 621.8600814816665, 657.5298661457059, 10914.890851091497, 10918.836217456725, 11043.171762117832, 11093.278936869097, 11339.26294790839, 12371.195872268296, 12412.875871241285, 12417.875821241785, 12425.937870310636, 12434.82390581649, 628.6573817269576, 636.4335741190345, 636.9617822930616, 777.2693667952107, 793.5913004802532, 642.1844257792601, 644.2397970394956, 767.3745897093878, 772.2200577416793, 780.0366547314063, 824.905135909369, 831.9750407487754, 833.3791325462508, 834.8038211020405, 835.1189495082685, 818.0, 822.0874828939872, 826.4586695714316, 826.7519744076753, 826.8428998490267, 484.13581756564713, 591.0159584292148, 595.6634501506678, 596.0790578556141, 609.6098496961948, 12710.300933448656, 12745.745085098339, 12747.808782868264, 12772.042096842773, 12790.360481975957, 10811.189160812892, 10869.510872010778, 10896.727581810439, 11101.944490277498, 11882.22765520247, 10675.78648427035, 10763.13894888407, 11049.944750276278, 11114.61098861544, 11294.096472282197, 3746.138388170736, 3764.228333191689, 3782.6326527572987, 3819.3507103792144, 3835.8082095895093, 44351.11297774059, 44912.10175796466, 44957.775211123735, 44991.32513012275, 45087.32369014467, 8991.460512369278, 9207.861882071775, 9244.075592440837, 9301.953490232505, 9368.718938431857, 15123.092614443169, 15213.0, 15243.542693719206, 15248.313825877862, 15286.847131528542, 6147.170132032208, 6277.24673039229, 6291.339409362043, 6388.840278993062, 6506.31683673217, 3370.1406141433777, 3393.609734880471, 3483.8432270547887, 3505.018594793462, 3505.4391297392294, 2548.362909272682, 2602.869856507205, 2604.4530648564014, 2617.1232637066796, 2623.553995820715, 477.9485132707987, 479.94960529144635, 481.11234771845886, 483.66143699410554, 494.6018455143644, 526.2816255810791, 560.6131769079323, 561.9269494965629, 570.4837122404231, 571.5827445964469, 7276.235995220532, 7324.2309557496765, 7470.813229669302, 7568.470207085451, 7580.54516728998, 500.059887411671, 526.2711145064113, 551.8179000929683, 564.5370795947284, 579.756502269048, 3393.2195595013286, 3413.74396920232, 3421.2473255883665, 3550.786952782841, 3601.9279614408088, 47313.290300645516, 48838.5116148834, 48885.75557122263, 54658.45341546613, 54727.0, 48025.0, 48026.27960580594, 48954.0, 48986.51013489819, 49610.255846162334, 45783.313250301275, 45785.0, 45895.0, 46305.768471157426, 46381.0, 8214.26071653554, 8269.669213231404, 8270.958645206976, 8403.873941890999, 8456.704015359433, 31119.0, 31281.374372512877, 31284.843575781975, 31687.366252675278, 31848.840755796075, 39660.40509392304, 40707.59292407156, 40925.0, 40940.59059409368, 41116.1776764469, 10314.278000540191, 10397.48012599352, 10609.681709548726, 10629.415382154055, 10833.133349331876, 11645.301281922773, 11670.358130302915, 11712.472938717608, 11773.05815534738, 12208.572699955459, 10484.842727359248, 10676.412797296223, 10999.065079468335, 11342.886571134513, 11368.0, 9858.334370720777, 9909.702708918743, 10319.896801032193, 10398.584056637952, 10587.100096491577, 10328.793424131625, 10650.520726567478, 10690.358578485315, 10861.185411094042, 10911.345319280868, 8762.167594078552, 8777.912220877965, 8923.643054277756, 9180.127887850911, 9660.661876834278, 9289.0, 9445.716628501155, 9655.372400793925, 9739.220862331138, 9821.901780982385], "value": 5741.777827926756, "unit": "score"}], "name": "dromaeo_css", "value": 6207.911507874935, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 114.0, 114.0, 112.0, 115.0, 113.0, 113.0, 112.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 129.0, 132.0, 160.0, 129.0, 160.0, 151.0, 140.0, 186.0, 129.0], "value": 147.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 163.0, 162.0, 161.0, 162.0, 160.0, 160.0, 161.0, 160.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 107.0, 76.0, 77.0, 75.0, 75.0, 97.0, 79.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 131.0, 84.0, 83.0, 83.0, 121.0, 83.0, 83.0, 135.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 147.0, 146.0, 146.0, 146.0, 145.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 97.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 63.0, 65.0, 64.0, 65.0, 93.0, 61.0, 61.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 82.0, 83.0, 84.0, 84.0, 85.0, 85.0, 84.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 142.0, 128.0, 128.0, 130.0, 129.0, 126.0, 173.0, 131.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 165.0, 172.0, 167.0, 168.0, 166.0, 166.0, 166.0, 164.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 66.0, 68.0, 65.0, 66.0, 67.0, 73.0, 65.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1543.5999999999997, "alertThreshold": 2.0}]} 12:38:22 INFO - Return code: 0 12:38:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:38:22 ERROR - # TBPL SUCCESS # 12:38:22 INFO - Running post-action listener: _resource_record_post_action 12:38:22 INFO - ##### 12:38:22 INFO - ##### Finished run-tests step (success) 12:38:22 INFO - ##### 12:38:22 INFO - Running post-run listener: _resource_record_post_run 12:38:22 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 521285632; Read time: 12; Write time: 515532 12:38:22 INFO - TinderboxPrint: CPU usage
13.2% 12:38:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:38:22 INFO - TinderboxPrint: I/O write bytes / time
521,285,632 / 515,532 12:38:22 INFO - TinderboxPrint: CPU idle
8,313.2 (86.8%) 12:38:22 INFO - TinderboxPrint: CPU user
1,218.1 (12.7%) 12:38:22 INFO - TinderboxPrint: Swap in / out
0 / 0 12:38:22 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 236937216; Read time: 0; Write time: 271416 12:38:22 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284143616; Read time: 12; Write time: 244116 12:38:22 INFO - Running post-run listener: _upload_blobber_files 12:38:22 INFO - Blob upload gear active. 12:38:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:38:22 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 12:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:38:23 INFO - (blobuploader) - INFO - Open directory for files ... 12:38:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:38:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:38:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:23 INFO - (blobuploader) - INFO - Done attempting. 12:38:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:38:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:28 INFO - (blobuploader) - INFO - Done attempting. 12:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:29 INFO - (blobuploader) - INFO - Done attempting. 12:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 12:38:29 INFO - Return code: 0 12:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:38:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa94a0f2256848584b435d1dc774afca845adcca9354bfb3958da9c77a6616c5392ed354735b0090c682643aba01086a3c743fe09d1b8ab439f3b2ce09f76674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6d91738081fabe5d2322be4ab296b00781c8796726eea06dcc88c54a26d4325cef4a9da18c784efa9629d5a6215d28543f4d411d4d2c66565ce7e5fff040fadf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e122a79329ff8e549608a498e7bce5e635f17db3af5024c3ad030bf33be00ade16b5a46051a5861ec531f518d7a42f209c67a0b93b542f6f3aede3db4472d33"} 12:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:38:29 INFO - Contents: 12:38:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa94a0f2256848584b435d1dc774afca845adcca9354bfb3958da9c77a6616c5392ed354735b0090c682643aba01086a3c743fe09d1b8ab439f3b2ce09f76674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6d91738081fabe5d2322be4ab296b00781c8796726eea06dcc88c54a26d4325cef4a9da18c784efa9629d5a6215d28543f4d411d4d2c66565ce7e5fff040fadf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e122a79329ff8e549608a498e7bce5e635f17db3af5024c3ad030bf33be00ade16b5a46051a5861ec531f518d7a42f209c67a0b93b542f6f3aede3db4472d33"} 12:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 12:38:29 INFO - Copying logs to upload dir... 12:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 12:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.379487 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-30 12:38:28.941755) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 12:38:28.944879) ========= 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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa94a0f2256848584b435d1dc774afca845adcca9354bfb3958da9c77a6616c5392ed354735b0090c682643aba01086a3c743fe09d1b8ab439f3b2ce09f76674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6d91738081fabe5d2322be4ab296b00781c8796726eea06dcc88c54a26d4325cef4a9da18c784efa9629d5a6215d28543f4d411d4d2c66565ce7e5fff040fadf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e122a79329ff8e549608a498e7bce5e635f17db3af5024c3ad030bf33be00ade16b5a46051a5861ec531f518d7a42f209c67a0b93b542f6f3aede3db4472d33"} build_url:https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/SRJ_mpA-T8q_eHcMy9rf_Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/aa94a0f2256848584b435d1dc774afca845adcca9354bfb3958da9c77a6616c5392ed354735b0090c682643aba01086a3c743fe09d1b8ab439f3b2ce09f76674", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/6d91738081fabe5d2322be4ab296b00781c8796726eea06dcc88c54a26d4325cef4a9da18c784efa9629d5a6215d28543f4d411d4d2c66565ce7e5fff040fadf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e122a79329ff8e549608a498e7bce5e635f17db3af5024c3ad030bf33be00ade16b5a46051a5861ec531f518d7a42f209c67a0b93b542f6f3aede3db4472d33"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 12:38:28.971373) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:38:28.971836) ========= 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=920470bf432669ad7e3a5387000001ca-1464633113.378052-1917291285 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004534 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:38:29.053564) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 12:38:29.053884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 12:38:29.054179) ========= ========= Total master_lag: 0.22 =========