builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-013 starttime: 1462982851.38 results: success (0) buildid: 20160511053102 builduid: ff72412b3b114d808b5757e6473d93f4 revision: 8cf323be5c58b28d8719401ebb0ef63f1d71d000 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.383933) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.384505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.384809) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.401180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.401506) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 09:07:31-- 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% 36.5M=0s 2016-05-11 09:07:31 (36.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105145 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.519833) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.520160) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015465 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.570571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:31.570912) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8cf323be5c58b28d8719401ebb0ef63f1d71d000 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8cf323be5c58b28d8719401ebb0ef63f1d71d000 --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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 09:07:31,776 truncating revision to first 12 chars 2016-05-11 09:07:31,776 Setting DEBUG logging. 2016-05-11 09:07:31,776 attempt 1/10 2016-05-11 09:07:31,776 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8cf323be5c58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 09:07:32,061 unpacking tar archive at: fx-team-8cf323be5c58/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572013 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:32.157175) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:32.157474) ========= script_repo_revision: 8cf323be5c58b28d8719401ebb0ef63f1d71d000 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:32.157862) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:32.158124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 09:07:32.350687) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-11 09:07:32.351042) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:32 INFO - MultiFileLogger online at 20160511 09:07:32 in /builds/slave/test 09:07:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:07:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:32 INFO - {'append_to_log': False, 09:07:32 INFO - 'base_work_dir': '/builds/slave/test', 09:07:32 INFO - 'blob_upload_branch': 'Fx-Team', 09:07:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:32 INFO - 'branch': 'Fx-Team', 09:07:32 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:32 INFO - 'config_files': ('talos/linux_config.py',), 09:07:32 INFO - 'default_actions': ('clobber', 09:07:32 INFO - 'read-buildbot-config', 09:07:32 INFO - 'download-and-extract', 09:07:32 INFO - 'populate-webroot', 09:07:32 INFO - 'create-virtualenv', 09:07:32 INFO - 'install', 09:07:32 INFO - 'run-tests'), 09:07:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:32 INFO - 'download_minidump_stackwalk': True, 09:07:32 INFO - 'download_symbols': 'ondemand', 09:07:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:32 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:32 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:32 INFO - 'installer_path': 'installer.exe', 09:07:32 INFO - 'log_level': 'info', 09:07:32 INFO - 'log_name': 'talos', 09:07:32 INFO - 'log_to_console': True, 09:07:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:32 INFO - 'opt_config_files': (), 09:07:32 INFO - 'pip_index': False, 09:07:32 INFO - 'sps_profile': False, 09:07:32 INFO - 'sps_profile_interval': 0, 09:07:32 INFO - 'suite': 'dromaeojs-e10s', 09:07:32 INFO - 'system_bits': '32', 09:07:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:32 INFO - 'title': 'talos-linux64-ix-013', 09:07:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:32 INFO - 'use_talos_json': True, 09:07:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:32 INFO - 'work_dir': 'build'} 09:07:32 INFO - ##### 09:07:32 INFO - ##### Running clobber step. 09:07:32 INFO - ##### 09:07:32 INFO - Running pre-action listener: _resource_record_pre_action 09:07:32 INFO - Running main action method: clobber 09:07:32 INFO - rmtree: /builds/slave/test/build 09:07:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:33 INFO - Running post-action listener: _resource_record_post_action 09:07:33 INFO - ##### 09:07:33 INFO - ##### Finished clobber step (success) 09:07:33 INFO - ##### 09:07:33 INFO - ##### 09:07:33 INFO - ##### Running read-buildbot-config step. 09:07:33 INFO - ##### 09:07:33 INFO - Running pre-action listener: _resource_record_pre_action 09:07:33 INFO - Running main action method: read_buildbot_config 09:07:33 INFO - Using buildbot properties: 09:07:33 INFO - { 09:07:33 INFO - "project": "", 09:07:33 INFO - "product": "firefox", 09:07:33 INFO - "script_repo_revision": "production", 09:07:33 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 09:07:33 INFO - "repository": "", 09:07:33 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 09:07:33 INFO - "buildid": "20160511053102", 09:07:33 INFO - "pgo_build": "True", 09:07:33 INFO - "basedir": "/builds/slave/test", 09:07:33 INFO - "buildnumber": 313, 09:07:33 INFO - "slavename": "talos-linux64-ix-013", 09:07:33 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:07:33 INFO - "platform": "ubuntu64_hw", 09:07:33 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 09:07:33 INFO - "branch": "fx-team", 09:07:33 INFO - "repo_path": "integration/fx-team", 09:07:33 INFO - "revision": "8cf323be5c58b28d8719401ebb0ef63f1d71d000", 09:07:33 INFO - "stage_platform": "linux64-pgo", 09:07:33 INFO - "builduid": "ff72412b3b114d808b5757e6473d93f4", 09:07:33 INFO - "slavebuilddir": "test" 09:07:33 INFO - } 09:07:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 09:07:33 INFO - Running post-action listener: _resource_record_post_action 09:07:33 INFO - ##### 09:07:33 INFO - ##### Finished read-buildbot-config step (success) 09:07:33 INFO - ##### 09:07:33 INFO - ##### 09:07:33 INFO - ##### Running download-and-extract step. 09:07:33 INFO - ##### 09:07:33 INFO - Running pre-action listener: _resource_record_pre_action 09:07:33 INFO - Running main action method: download_and_extract 09:07:33 INFO - mkdir: /builds/slave/test/build/tests 09:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:33 INFO - https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:07:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:07:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:07:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:33 INFO - Downloaded 1361 bytes. 09:07:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 09:07:33 INFO - Using the following test package requirements: 09:07:33 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:07:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:07:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'jsshell-linux-x86_64.zip'], 09:07:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:07:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 09:07:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:07:33 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:07:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:07:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 09:07:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:07:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:33 INFO - https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:07:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:07:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:07:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:35 INFO - Downloaded 23902241 bytes. 09:07:35 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'] 09:07:35 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 09:07:36 INFO - Return code: 0 09:07:36 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 09:07:36 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')]} 09:07:36 INFO - https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:07:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:07:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 09:07:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:37 INFO - Downloaded 23902241 bytes. 09:07:37 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'] 09:07:37 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 09:07:39 INFO - Return code: 0 09:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:39 INFO - https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:07:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:07:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:07:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:40 INFO - Downloaded 10840152 bytes. 09:07:40 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'] 09:07:40 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 09:07:41 INFO - Return code: 0 09:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:41 INFO - https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:07:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:07:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:07:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja2kUphES8aukwqBa1x3DQ/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 09:07:42 INFO - Downloaded 58326547 bytes. 09:07:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:07:42 INFO - mkdir: /builds/slave/test/properties 09:07:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:42 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:42 INFO - Contents: 09:07:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:07:43 INFO - Running post-action listener: _resource_record_post_action 09:07:43 INFO - Running post-action listener: set_extra_try_arguments 09:07:43 INFO - ##### 09:07:43 INFO - ##### Finished download-and-extract step (success) 09:07:43 INFO - ##### 09:07:43 INFO - ##### 09:07:43 INFO - ##### Running populate-webroot step. 09:07:43 INFO - ##### 09:07:43 INFO - Running pre-action listener: _resource_record_pre_action 09:07:43 INFO - Running main action method: populate_webroot 09:07:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:43 INFO - '--tppagecycles', 09:07:43 INFO - '7'], 09:07:43 INFO - 'tests': ['tsvgm']}}, 09:07:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['damp', 'tps']}, 09:07:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['damp', 'tps']}, 09:07:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['dromaeo_dom']}, 09:07:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['a11yr', 09:07:43 INFO - 'ts_paint', 09:07:43 INFO - 'tpaint', 09:07:43 INFO - 'sessionrestore', 09:07:43 INFO - 'sessionrestore_no_auto_restore', 09:07:43 INFO - 'tabpaint']}, 09:07:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tsvgx', 09:07:43 INFO - 'tsvgr_opacity', 09:07:43 INFO - 'tart', 09:07:43 INFO - 'tscrollx', 09:07:43 INFO - 'cart']}, 09:07:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:43 INFO - 'tsvgr_opacity', 09:07:43 INFO - 'tart', 09:07:43 INFO - 'tscrollx', 09:07:43 INFO - 'cart']}, 09:07:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s'], 09:07:43 INFO - 'tests': ['tp5o']}, 09:07:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'tests': ['tp5o']}, 09:07:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--disable-e10s', 09:07:43 INFO - '--xperf_path', 09:07:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:43 INFO - 'tests': ['tp5n']}, 09:07:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:07:43 INFO - 'talos_options': ['--xperf_path', 09:07:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:43 INFO - 'tests': ['tp5n']}}, 09:07:43 INFO - 'talos.zip': {'path': '', 09:07:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:43 INFO - Running post-action listener: _resource_record_post_action 09:07:43 INFO - ##### 09:07:43 INFO - ##### Finished populate-webroot step (success) 09:07:43 INFO - ##### 09:07:43 INFO - ##### 09:07:43 INFO - ##### Running create-virtualenv step. 09:07:43 INFO - ##### 09:07:43 INFO - Running pre-action listener: _resource_record_pre_action 09:07:43 INFO - Running main action method: create_virtualenv 09:07:43 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:43 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 09:07:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:43 INFO - Using real prefix '/usr' 09:07:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:44 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:46 INFO - Installing pip.................done. 09:07:46 INFO - Return code: 0 09:07:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:07:46 INFO - Using env: {'DISPLAY': ':0', 09:07:46 INFO - 'HOME': '/home/cltbld', 09:07:46 INFO - 'LANG': 'en_US.UTF-8', 09:07:46 INFO - 'LANGUAGE': 'en_US:en', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MAIL': '/var/mail/cltbld', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SHLVL': '1', 09:07:46 INFO - 'TERM': 'linux', 09:07:46 INFO - 'TMOUT': '86400', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - '_': '/tools/buildbot/bin/python'} 09:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:07:46 INFO - Cleaning up... 09:07:46 INFO - Return code: 0 09:07:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:07:46 INFO - Using env: {'DISPLAY': ':0', 09:07:46 INFO - 'HOME': '/home/cltbld', 09:07:46 INFO - 'LANG': 'en_US.UTF-8', 09:07:46 INFO - 'LANGUAGE': 'en_US:en', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MAIL': '/var/mail/cltbld', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SHLVL': '1', 09:07:46 INFO - 'TERM': 'linux', 09:07:46 INFO - 'TMOUT': '86400', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - '_': '/tools/buildbot/bin/python'} 09:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:46 INFO - Downloading/unpacking psutil>=0.7.1 09:07:46 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 09:07:46 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 09:07:46 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 09:07:46 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 09:07:46 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 09:07:46 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 09:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:48 INFO - Installing collected packages: psutil 09:07:48 INFO - Running setup.py install for psutil 09:07:48 INFO - building 'psutil._psutil_linux' extension 09:07:48 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 09:07:48 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 09:07:48 INFO - building 'psutil._psutil_posix' extension 09:07:48 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 09:07:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:07:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:49 INFO - Successfully installed psutil 09:07:49 INFO - Cleaning up... 09:07:49 INFO - Return code: 0 09:07:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:07:49 INFO - Using env: {'DISPLAY': ':0', 09:07:49 INFO - 'HOME': '/home/cltbld', 09:07:49 INFO - 'LANG': 'en_US.UTF-8', 09:07:49 INFO - 'LANGUAGE': 'en_US:en', 09:07:49 INFO - 'LOGNAME': 'cltbld', 09:07:49 INFO - 'MAIL': '/var/mail/cltbld', 09:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:49 INFO - 'MOZ_NO_REMOTE': '1', 09:07:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:49 INFO - 'NO_EM_RESTART': '1', 09:07:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:49 INFO - 'PWD': '/builds/slave/test', 09:07:49 INFO - 'SHELL': '/bin/bash', 09:07:49 INFO - 'SHLVL': '1', 09:07:49 INFO - 'TERM': 'linux', 09:07:49 INFO - 'TMOUT': '86400', 09:07:49 INFO - 'USER': 'cltbld', 09:07:49 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:49 INFO - '_': '/tools/buildbot/bin/python'} 09:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:07:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:07:51 INFO - Installing collected packages: mozsystemmonitor 09:07:51 INFO - Running setup.py install for mozsystemmonitor 09:07:51 INFO - Successfully installed mozsystemmonitor 09:07:51 INFO - Cleaning up... 09:07:51 INFO - Return code: 0 09:07:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:07:51 INFO - Using env: {'DISPLAY': ':0', 09:07:51 INFO - 'HOME': '/home/cltbld', 09:07:51 INFO - 'LANG': 'en_US.UTF-8', 09:07:51 INFO - 'LANGUAGE': 'en_US:en', 09:07:51 INFO - 'LOGNAME': 'cltbld', 09:07:51 INFO - 'MAIL': '/var/mail/cltbld', 09:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:51 INFO - 'MOZ_NO_REMOTE': '1', 09:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:51 INFO - 'NO_EM_RESTART': '1', 09:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:51 INFO - 'PWD': '/builds/slave/test', 09:07:51 INFO - 'SHELL': '/bin/bash', 09:07:51 INFO - 'SHLVL': '1', 09:07:51 INFO - 'TERM': 'linux', 09:07:51 INFO - 'TMOUT': '86400', 09:07:51 INFO - 'USER': 'cltbld', 09:07:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:51 INFO - '_': '/tools/buildbot/bin/python'} 09:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:53 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 09:07:53 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 09:07:53 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 09:07:53 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 09:07:53 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 09:07:53 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 09:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:54 INFO - Downloading docopt-0.6.1.tar.gz 09:07:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:54 INFO - Running setup.py install for blobuploader 09:07:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:54 INFO - Running setup.py install for requests 09:07:54 INFO - Running setup.py install for docopt 09:07:54 INFO - Successfully installed blobuploader requests docopt 09:07:54 INFO - Cleaning up... 09:07:54 INFO - Return code: 0 09:07:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:54 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 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:54 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 09:07:54 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 09:07:54 INFO - Using env: {'DISPLAY': ':0', 09:07:54 INFO - 'HOME': '/home/cltbld', 09:07:54 INFO - 'LANG': 'en_US.UTF-8', 09:07:54 INFO - 'LANGUAGE': 'en_US:en', 09:07:54 INFO - 'LOGNAME': 'cltbld', 09:07:54 INFO - 'MAIL': '/var/mail/cltbld', 09:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:54 INFO - 'MOZ_NO_REMOTE': '1', 09:07:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:54 INFO - 'NO_EM_RESTART': '1', 09:07:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:54 INFO - 'PWD': '/builds/slave/test', 09:07:54 INFO - 'SHELL': '/bin/bash', 09:07:54 INFO - 'SHLVL': '1', 09:07:54 INFO - 'TERM': 'linux', 09:07:54 INFO - 'TMOUT': '86400', 09:07:54 INFO - 'USER': 'cltbld', 09:07:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:54 INFO - '_': '/tools/buildbot/bin/python'} 09:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:55 INFO - Running setup.py (path:/tmp/pip-26VJtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:55 INFO - Running setup.py (path:/tmp/pip-qUckDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:55 INFO - Running setup.py (path:/tmp/pip-Q3Elj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:55 INFO - Running setup.py (path:/tmp/pip-B4noR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:55 INFO - Running setup.py (path:/tmp/pip-kpV8oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:55 INFO - Running setup.py (path:/tmp/pip-vPhYAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:55 INFO - Running setup.py (path:/tmp/pip-DEeSVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:55 INFO - Running setup.py (path:/tmp/pip-kTVv8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:55 INFO - Running setup.py (path:/tmp/pip-1tkYuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:55 INFO - Running setup.py (path:/tmp/pip-U0raMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:55 INFO - Running setup.py (path:/tmp/pip-kDYZh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:55 INFO - Running setup.py (path:/tmp/pip-NMTEu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:56 INFO - Running setup.py (path:/tmp/pip-TqEQqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:56 INFO - Running setup.py (path:/tmp/pip-GQX8lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:56 INFO - Running setup.py (path:/tmp/pip-kac8g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:56 INFO - Running setup.py (path:/tmp/pip-2hO9Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:56 INFO - Running setup.py (path:/tmp/pip-lUcl1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:56 INFO - Running setup.py install for manifestparser 09:07:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:56 INFO - Running setup.py install for mozcrash 09:07:56 INFO - Running setup.py install for mozdebug 09:07:56 INFO - Running setup.py install for mozdevice 09:07:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:56 INFO - Running setup.py install for mozfile 09:07:56 INFO - Running setup.py install for mozhttpd 09:07:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozinfo 09:07:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozInstall 09:07:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozleak 09:07:57 INFO - Running setup.py install for mozlog 09:07:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for moznetwork 09:07:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozprocess 09:07:57 INFO - Running setup.py install for mozprofile 09:07:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozrunner 09:07:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:57 INFO - Running setup.py install for mozscreenshot 09:07:57 INFO - Running setup.py install for moztest 09:07:58 INFO - Running setup.py install for mozversion 09:07:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:58 INFO - Cleaning up... 09:07:58 INFO - Return code: 0 09:07:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:07:58 INFO - Using env: {'DISPLAY': ':0', 09:07:58 INFO - 'HOME': '/home/cltbld', 09:07:58 INFO - 'LANG': 'en_US.UTF-8', 09:07:58 INFO - 'LANGUAGE': 'en_US:en', 09:07:58 INFO - 'LOGNAME': 'cltbld', 09:07:58 INFO - 'MAIL': '/var/mail/cltbld', 09:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:58 INFO - 'MOZ_NO_REMOTE': '1', 09:07:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:58 INFO - 'NO_EM_RESTART': '1', 09:07:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:58 INFO - 'PWD': '/builds/slave/test', 09:07:58 INFO - 'SHELL': '/bin/bash', 09:07:58 INFO - 'SHLVL': '1', 09:07:58 INFO - 'TERM': 'linux', 09:07:58 INFO - 'TMOUT': '86400', 09:07:58 INFO - 'USER': 'cltbld', 09:07:58 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:58 INFO - '_': '/tools/buildbot/bin/python'} 09:07:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:58 INFO - Running setup.py (path:/tmp/pip-2P1BOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:58 INFO - Running setup.py (path:/tmp/pip-VKT00K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:58 INFO - Running setup.py (path:/tmp/pip-Ru2MMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:58 INFO - Running setup.py (path:/tmp/pip-eY8yvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:58 INFO - Running setup.py (path:/tmp/pip-hFxG3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:58 INFO - Running setup.py (path:/tmp/pip-PfnEJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:58 INFO - Running setup.py (path:/tmp/pip-jw1PkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:58 INFO - Running setup.py (path:/tmp/pip-CdAgLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:58 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)) 09:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:58 INFO - Running setup.py (path:/tmp/pip-CuqXMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:59 INFO - Running setup.py (path:/tmp/pip-xt40ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:59 INFO - Running setup.py (path:/tmp/pip-Hoybih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:59 INFO - Running setup.py (path:/tmp/pip-YxM7BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:59 INFO - Running setup.py (path:/tmp/pip-k917cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:59 INFO - Running setup.py (path:/tmp/pip-iIiRyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:59 INFO - Running setup.py (path:/tmp/pip-lNnEYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:59 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)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:59 INFO - Running setup.py (path:/tmp/pip-yPxjLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:59 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)) 09:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:59 INFO - Running setup.py (path:/tmp/pip-oFeaEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:59 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)) 09:07:59 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)) 09:07:59 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)) 09:07:59 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)) 09:07:59 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)) 09:07:59 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)) 09:07:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:01 INFO - Downloading blessings-1.6.tar.gz 09:08:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:01 INFO - Installing collected packages: blessings 09:08:01 INFO - Running setup.py install for blessings 09:08:01 INFO - Successfully installed blessings 09:08:01 INFO - Cleaning up... 09:08:01 INFO - Return code: 0 09:08:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:01 INFO - Reading from file tmpfile_stdout 09:08:01 INFO - Current package versions: 09:08:01 INFO - argparse == 1.2.1 09:08:01 INFO - blessings == 1.6 09:08:01 INFO - blobuploader == 1.2.4 09:08:01 INFO - docopt == 0.6.1 09:08:01 INFO - manifestparser == 1.1 09:08:01 INFO - mozInstall == 1.12 09:08:01 INFO - mozcrash == 0.17 09:08:01 INFO - mozdebug == 0.1 09:08:01 INFO - mozdevice == 0.48 09:08:01 INFO - mozfile == 1.2 09:08:01 INFO - mozhttpd == 0.7 09:08:01 INFO - mozinfo == 0.9 09:08:01 INFO - mozleak == 0.1 09:08:01 INFO - mozlog == 3.1 09:08:01 INFO - moznetwork == 0.27 09:08:01 INFO - mozprocess == 0.22 09:08:01 INFO - mozprofile == 0.28 09:08:01 INFO - mozrunner == 6.11 09:08:01 INFO - mozscreenshot == 0.1 09:08:01 INFO - mozsystemmonitor == 0.0 09:08:01 INFO - moztest == 0.7 09:08:01 INFO - mozversion == 1.4 09:08:01 INFO - psutil == 3.1.1 09:08:01 INFO - requests == 1.2.3 09:08:01 INFO - wsgiref == 0.1.2 09:08:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:01 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 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:08:01 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 09:08:01 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 09:08:01 INFO - Using env: {'DISPLAY': ':0', 09:08:01 INFO - 'HOME': '/home/cltbld', 09:08:01 INFO - 'LANG': 'en_US.UTF-8', 09:08:01 INFO - 'LANGUAGE': 'en_US:en', 09:08:01 INFO - 'LOGNAME': 'cltbld', 09:08:01 INFO - 'MAIL': '/var/mail/cltbld', 09:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:01 INFO - 'MOZ_NO_REMOTE': '1', 09:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:01 INFO - 'NO_EM_RESTART': '1', 09:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:01 INFO - 'PWD': '/builds/slave/test', 09:08:01 INFO - 'SHELL': '/bin/bash', 09:08:01 INFO - 'SHLVL': '1', 09:08:01 INFO - 'TERM': 'linux', 09:08:01 INFO - 'TMOUT': '86400', 09:08:01 INFO - 'USER': 'cltbld', 09:08:01 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:01 INFO - '_': '/tools/buildbot/bin/python'} 09:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:02 INFO - Cleaning up... 09:08:02 INFO - Return code: 0 09:08:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:08:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:08:02 INFO - Using env: {'DISPLAY': ':0', 09:08:02 INFO - 'HOME': '/home/cltbld', 09:08:02 INFO - 'LANG': 'en_US.UTF-8', 09:08:02 INFO - 'LANGUAGE': 'en_US:en', 09:08:02 INFO - 'LOGNAME': 'cltbld', 09:08:02 INFO - 'MAIL': '/var/mail/cltbld', 09:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:02 INFO - 'MOZ_NO_REMOTE': '1', 09:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:02 INFO - 'NO_EM_RESTART': '1', 09:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:02 INFO - 'PWD': '/builds/slave/test', 09:08:02 INFO - 'SHELL': '/bin/bash', 09:08:02 INFO - 'SHLVL': '1', 09:08:02 INFO - 'TERM': 'linux', 09:08:02 INFO - 'TMOUT': '86400', 09:08:02 INFO - 'USER': 'cltbld', 09:08:02 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:02 INFO - '_': '/tools/buildbot/bin/python'} 09:08:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:02 INFO - Downloading/unpacking jsonschema 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:04 INFO - Downloading/unpacking functools32 (from jsonschema) 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:04 INFO - warning: no files found matching '*.txt' 09:08:04 INFO - no previously-included directories found matching 'build' 09:08:04 INFO - no previously-included directories found matching 'dist' 09:08:04 INFO - no previously-included directories found matching '.git*' 09:08:04 INFO - Installing collected packages: jsonschema, functools32 09:08:04 INFO - Running setup.py install for functools32 09:08:04 INFO - warning: no files found matching '*.txt' 09:08:04 INFO - no previously-included directories found matching 'build' 09:08:04 INFO - no previously-included directories found matching 'dist' 09:08:04 INFO - no previously-included directories found matching '.git*' 09:08:04 INFO - Successfully installed jsonschema functools32 09:08:04 INFO - Cleaning up... 09:08:04 INFO - Return code: 0 09:08:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:08: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')]} 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08: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')]} 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:08:04 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 0x7f309b2def10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b078a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd2770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, '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': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', '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 09:08:04 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 09:08:04 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 09:08:04 INFO - Using env: {'DISPLAY': ':0', 09:08:04 INFO - 'HOME': '/home/cltbld', 09:08:04 INFO - 'LANG': 'en_US.UTF-8', 09:08:04 INFO - 'LANGUAGE': 'en_US:en', 09:08:04 INFO - 'LOGNAME': 'cltbld', 09:08:04 INFO - 'MAIL': '/var/mail/cltbld', 09:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:04 INFO - 'MOZ_NO_REMOTE': '1', 09:08:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:04 INFO - 'NO_EM_RESTART': '1', 09:08:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:04 INFO - 'PWD': '/builds/slave/test', 09:08:04 INFO - 'SHELL': '/bin/bash', 09:08:04 INFO - 'SHLVL': '1', 09:08:04 INFO - 'TERM': 'linux', 09:08:04 INFO - 'TMOUT': '86400', 09:08:04 INFO - 'USER': 'cltbld', 09:08:04 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:04 INFO - '_': '/tools/buildbot/bin/python'} 09:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:04 INFO - Downloading/unpacking flake8 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:08:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:06 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 09:08:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:08:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08: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 09:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:08:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:07 INFO - no previously-included directories found matching 'docs/_build' 09:08:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:08:07 INFO - Running setup.py install for pep8 09:08:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:08:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:08:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:08:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:08:07 INFO - no previously-included directories found matching 'docs/_build' 09:08:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:08:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:08:07 INFO - Cleaning up... 09:08:07 INFO - Return code: 0 09:08:07 INFO - Running post-action listener: _resource_record_post_action 09:08:07 INFO - Running post-action listener: _start_resource_monitoring 09:08:07 INFO - Starting resource monitoring. 09:08:07 INFO - ##### 09:08:07 INFO - ##### Finished create-virtualenv step (success) 09:08:07 INFO - ##### 09:08:07 INFO - ##### 09:08:07 INFO - ##### Running install step. 09:08:07 INFO - ##### 09:08:07 INFO - Running pre-action listener: _resource_record_pre_action 09:08:07 INFO - Running main action method: install 09:08:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:08 INFO - Reading from file tmpfile_stdout 09:08:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:08 INFO - Reading from file tmpfile_stdout 09:08:08 INFO - Output received: 09:08:08 INFO - Usage: mozinstall [options] installer 09:08:08 INFO - Options: 09:08:08 INFO - -h, --help show this help message and exit 09:08:08 INFO - -d DEST, --destination=DEST 09:08:08 INFO - Directory to install application into. [default: 09:08:08 INFO - "/builds/slave/test"] 09:08:08 INFO - --app=APP Application being installed. [default: firefox] 09:08:08 INFO - mkdir: /builds/slave/test/build/application 09:08:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:08:24 INFO - Reading from file tmpfile_stdout 09:08:24 INFO - Output received: 09:08:24 INFO - /builds/slave/test/build/application/firefox/firefox 09:08:24 INFO - Running post-action listener: _resource_record_post_action 09:08:24 INFO - ##### 09:08:24 INFO - ##### Finished install step (success) 09:08:24 INFO - ##### 09:08:24 INFO - ##### 09:08:24 INFO - ##### Running run-tests step. 09:08:24 INFO - ##### 09:08:24 INFO - Running pre-action listener: _resource_record_pre_action 09:08:24 INFO - Running main action method: run_tests 09:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:24 INFO - Python 2.7.3 09:08:24 INFO - Return code: 0 09:08:24 INFO - grabbing minidump binary from tooltool 09:08:24 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')]} 09:08:24 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 0x2c619c0>, '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 0x2c628c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c62d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:24 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 09:08:24 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 09:08:24 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 09:08:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:25 INFO - Return code: 0 09:08:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:08:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:08:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:08:25 INFO - Return code: 0 09:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:08:25 INFO - Using env: {'DISPLAY': ':0', 09:08:25 INFO - 'HOME': '/home/cltbld', 09:08:25 INFO - 'LANG': 'en_US.UTF-8', 09:08:25 INFO - 'LANGUAGE': 'en_US:en', 09:08:25 INFO - 'LOGNAME': 'cltbld', 09:08:25 INFO - 'MAIL': '/var/mail/cltbld', 09:08:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:25 INFO - 'MOZ_NO_REMOTE': '1', 09:08:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:25 INFO - 'NO_EM_RESTART': '1', 09:08:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:25 INFO - 'PWD': '/builds/slave/test', 09:08:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:25 INFO - 'SHELL': '/bin/bash', 09:08:25 INFO - 'SHLVL': '1', 09:08:25 INFO - 'TERM': 'linux', 09:08:25 INFO - 'TMOUT': '86400', 09:08:25 INFO - 'USER': 'cltbld', 09:08:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214', 09:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:25 INFO - '_': '/tools/buildbot/bin/python'} 09:08:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:08:26 INFO - mozversion application_buildid: 20160511053102 09:08:26 INFO - mozversion application_changeset: 8cf323be5c58b28d8719401ebb0ef63f1d71d000 09:08:26 INFO - mozversion application_display_name: Nightly 09:08:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:26 INFO - mozversion application_name: Firefox 09:08:26 INFO - mozversion application_remotingname: firefox 09:08:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:08:26 INFO - mozversion application_vendor: Mozilla 09:08:26 INFO - mozversion application_version: 49.0a1 09:08:26 INFO - mozversion platform_buildid: 20160511053102 09:08:26 INFO - mozversion platform_changeset: 8cf323be5c58b28d8719401ebb0ef63f1d71d000 09:08:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:08:26 INFO - mozversion platform_version: 49.0a1 09:08:26 INFO - using testdate: 1462982906 09:08:26 INFO - actual date: 1462982906 09:08:26 INFO - starting webserver on 'localhost:57391' 09:08:26 INFO - SUITE-START | Running 2 tests 09:08:26 INFO - TEST-START | dromaeo_css 09:08:26 INFO - operating with platform_type : linux_ 09:08:26 INFO - Initialising browser for dromaeo_css test... 09:08:26 INFO - TEST-INFO | started process 13206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppxeoDV/profile http://localhost:57391/getInfo.html) 09:08:30 INFO - PROCESS | 13206 | 09:08:30 INFO - PROCESS | 13206 | (process:13273): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:08:30 INFO - PROCESS | 13206 | __metrics Screen width/height:1600/1200 09:08:30 INFO - PROCESS | 13206 | colorDepth:24 09:08:30 INFO - PROCESS | 13206 | Browser inner width/height: 1024/697 09:08:30 INFO - PROCESS | 13206 | __metrics 09:08:31 ERROR - PROCESS | 13206 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 09:08:32 INFO - TEST-INFO | 13206: exit 0 09:08:32 INFO - Browser initialized. 09:08:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:32 INFO - TEST-INFO | started process 13324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppxeoDV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:34 INFO - PROCESS | 13324 | 09:08:34 INFO - PROCESS | 13324 | (process:13385): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:08:44 INFO - PROCESS | 13324 | RSS: Main: 162918400 09:08:44 INFO - PROCESS | 13324 | 09:09:33 INFO - PROCESS | 13324 | 1462982973936 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] 09:11:41 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57391/tests/dromaeo/cssquery-ext.html) 09:11:42 INFO - PROCESS | 13324 | RSS: Main: 166354944 09:11:42 INFO - PROCESS | 13324 | 09:14:44 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-ext.html (next: http://localhost:57391/tests/dromaeo/cssquery-jquery.html) 09:14:45 INFO - PROCESS | 13324 | RSS: Main: 166580224 09:14:45 INFO - PROCESS | 13324 | 09:17:47 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57391/tests/dromaeo/cssquery-mootools.html) 09:17:47 INFO - PROCESS | 13324 | RSS: Main: 167145472 09:17:47 INFO - PROCESS | 13324 | 09:20:49 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57391/tests/dromaeo/cssquery-prototype.html) 09:20:50 INFO - PROCESS | 13324 | RSS: Main: 164888576 09:20:50 INFO - PROCESS | 13324 | 09:23:52 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57391/tests/dromaeo/cssquery-yui.html) 09:23:53 INFO - PROCESS | 13324 | RSS: Main: 165969920 09:23:53 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | Cycle 1(1): loaded http://localhost:57391/tests/dromaeo/cssquery-yui.html (next: http://localhost:57391/tests/dromaeo/cssquery-dojo.html) 09:26:55 INFO - PROCESS | 13324 | RSS: Main: 168112128 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | __start_tp_report 09:26:55 INFO - PROCESS | 13324 | _x_x_mozilla_page_load 09:26:55 INFO - PROCESS | 13324 | _x_x_mozilla_page_load_details 09:26:55 INFO - PROCESS | 13324 | |i|pagename|runs| 09:26:55 INFO - PROCESS | 13324 | |0;dojo.html;3245.350929814037;3250.236194494295;3285.983570082149;3289.194147433879;3296.2583418730787;5617.466340697637;5646.774129034834;5693.572982026349;5695;5706.65760054397;5498.257735205756;5548.500634942854;5559;5619.241402410673;5657.2928383952;5022.547970682647;5052.545270925616;5176.637635365526;5207.192885102815;5214.979472316329;1024.6706214962578;5021.723805190732;5050.898982020358;5063.16457784467;5200.973995130019;5090.54185123337;5116.462771408986;5157.329547158883;5158.045761534109;5217.2695822584865;5174.870628234286;5183.222516622537;5189.948100518985;5230.267762513251;5276.551493123089;4935.9753201234125;4964.453910069926;4965.379327584052;4981.12830254704;5115.309433226504;5410.972945135289;5418.241446197536;5426.21319908615;5432.674039557639;5434.8913021739345;5880.206172166745;5895.410458954113;5899.46904778572;5913.822585322448;5955.225820643332;5943.257092863392;5943.940560594382;5945.048792193272;5955.731992060368;5977.910331345033;5559.749811258463;5568.916466253009;5640.153976903456;5650.971745141248;5656.462636049569;6100.187591190193;6158;6166.876662466815;6170.228721409824;6176.567640265228;5911.320198177179;5912.113183022495;5954.612950158225;5960.135780311831;6012.549058820606;6081.726322315506;6090.36051214625;6090.878182436327;6094.695265236721;6098.35967223444;5871.853203669943;5912.3496415394275;5944.821655350346;6055.515558755289;6061.272647282354;5963.165156878041;5969.104634304889;5985.012472941916;6002.159697642333;6043.8489037773525;4489.0572979674635;4503.527129651405;4518.615917647036;4534.183846907522;4563.383943167696;3953;3981.8606348777653;4023.1551374211745;4028.3957406389277;4044.51465824103;5507.091329930517;5619.719014049281;5648.293963254593;5688.033034384164;5898.764049437975;5945.108233764883;5997.910031349533;5998.940010599926;6005.249343832021;6013.729382177812;5608.1587761835235;5610.831675049755;5671.376148623648;5673.943260567342;5688.288963879515;5134.743262836918;5154.613403994716;5179.45615710345;5185.688858668492;5199.194124910645;6228.377162283736;6261.123442718023;6269.529785266123;6286.088517164985;6288.52836037299;5917.615355001911;6036.094585812163;6051.485623721945;6104.053871649901;6118;6137.591499645514;6235.75056997715;6241.375862413723;6271.843203919939;6283.37166283368;6006.669633170043;6015.699215039318;6016.729247183975;6023.1567580537885;6061.272647282354;5897.174395584536;5962.344142144448;5983.970080149571;6095.299040610386;6105.297890742621;5841.94158058431;5872.941270587239;5886.05823068307;5900.970495147497;5959.1657167995645;5860.970695146497;5897.203877476486;5902.970485147718;5909.970450147722;5918.7928422505;5639.154126880837;5691.8861622768145;5696.743646535999;5740.712964351849;5768.365479797303;5881.588288819913;5893.233879595625;5897.233359663388;5916.645001299936;5922.348541660328;5765.394633563583;5774.711264436845;5775.711214439178;5780.161876527964;5797.420257974169 09:26:55 INFO - PROCESS | 13324 | |1;ext.html;604.1873679900532;652.5497406789317;657.7007461604967;671.7413795688658;675.8580698053408;43251.783741081286;44108.558914410845;44270.11459770802;44312.55687443125;47969.28046079303;45234.54765452344;46178.307325390146;46190.769046154724;46198.30702539456;46263.53736462635;8869.113088691143;8999.190072893438;9006.32452566057;9012.143846334588;9013.098690131;8827.911720882774;8961.910380896205;8968.327375446836;8973.685920992766;8975;8883.866741998876;8983.820323593556;8987.820243595124;9020.368574199809;9032.819343613124;8890.155435233642;8939.106089391074;8942.329325300596;8997.550122493914;9034.954825225897;8711.0417854036;8761.167689069529;8773.078826723166;8783.38516303859;8797.560121993938;41367.7931610343;41777;41797;42022.78988605068;42044.57955420437;3212.4860022396306;3283.3761585298957;3307.751918606114;3381.458966565338;3572.392693242155;32187.83906080478;32226.6777332226;32572.674273257202;32765.83617081923;33266.83366583152;4151;4159.7920103994675;4179.498460184797;4196.9370459443135;4269.295566231568;312564;312699;312892;313022;314302;32448.513272300934;32968;59918.400815991285;59965.10052349218;60210;37971.81014094912;38142;38164.236715266095;38197.236055278736;38351.23297534034;15046.623834404227;15057.623559411102;15075.170865602497;15161.241937903062;15202.619934501727;12450.75098498025;12527.373631318398;12546.81179782304;12551.184173028723;12600;8612.095729948265;8627.95686021566;8630.180132887366;8645.654173833102;8672.913270867337;3324.384988777084;3400.6599340065795;3400.812955287483;3402.3535528249554;3428.63999280072;1049.58541376156;1105.8009558279587;1128.9500764289126;1167.9488460385721;1176.8999635031118;32835.50746738801;32894.17764555905;33222;33478;33595.83202084023;1128.8363187337955;1152.6484422251294;1171.6953592065954;1225.7854875396947;1247.732753981386;17251.223694933506;17303.480895572902;17321.220545075503;17528.91235543814;17660.911695441442;490682;493082;493761;494024;497858;58546.707266463396;59172.70413647904;59267.40732592705;59500;59927.400725992185;1395.169873925013;1399.0766094377661;1405.8523854995283;1406.184413040434;1421.0834012062369;31471.68528314779;32239.677603224605;32939.67060329462;33139.171520712174;33363.49954750632;52073.21890171574;54981.72509137429;55483.722581386835;56131.43868561262;61685.53257869635;418771;420768;420979;421892;425168;44720;44831.103377931606;44986.77506612446;45195.32207016832;45378.77310613426;49337;49650.255246170615;49933.500664992884;50332;50483.242751358026;41105.79447102745;41740.58259417367;42438.151236975704;42450.78774606107;43442;41061.38407923943;42060;42226.788866055475;42464.36303455512;42472.36291455692;36301.45547816838;42602;44787.32819007652;45225.54774452213;45278;41005;44478.332825007004;44600.776996114815;45003.77498112489;45173.54826451825;33137.33725325528;34383.828080859435;34467.655323446445;35255.11862203466;35285.82357088301 09:26:55 INFO - PROCESS | 13324 | |2;jquery.html;405.13099401783194;409.28171059790066;419.4149161919122;420.9557996410377;428.4644194756554;18352.174152163152;19810.302551017874;19858;20072.397828065146;20686.586268274626;18884.43346699601;19082.236710531597;19870.801291987074;20001.899990500064;20079.297224597143;5362.115250983593;5730.3123625165035;5759.452851979056;5760.7695692172265;5761.9135712964335;5656.406077361879;5678.233438485813;5730.656160630376;5736.282964629421;5749.223854779593;5551.139573366135;5659.037963546187;5681.261436013333;5684.971575142118;5700.572457065716;5521.171824226358;5659.40576239493;5690.2033715279895;5695.401982791809;5711.485966263015;5082.390113186404;5115.411727651293;5124.641275110744;5146.150885103953;5160;16954.576135596584;18688.159032843556;22903.312900613008;22980.19569315066;23104;2336.8013718833918;2416.1664225842055;2425.454272788626;2560.8591527465983;2780.593772628534;14734.4842930498;15026.098434093989;15099.169545674984;15214.391424343014;15553.688926221525;1894.6328712318102;1944.0646364201368;2029.5839352932614;2047.2118234479683;2081.2299449203742;69811.65094174497;72309.27690723079;74764;76796;78109.60945195238;10933.289336193122;10949.83575246372;11233.831492527619;11299.20905536621;11642.88357116418;16179.919100404422;16327.510174694778;16373.263203155886;16427.260773265232;16435.342586296654;8039.839203215903;8363.916360836436;8373.288270496954;8470.745877623558;8508.702195423131;7817.022872140982;7837.294643482114;7914.24814642608;7998.12020677725;8011.959940200261;4913.410390753133;4934.703917764946;5007.6494645375205;5015.749212539358;5105.136625131874;1976;1988.3239698502289;2029;2031.7765045844944;2094;916.7937214126769;964.6961207219704;1001.9348742331725;1036.409246729357;1106.5573770491867;17934.5516362092;19193.90403047976;19853.702194467096;20018.299359522345;20092.799072009093;980.3186785184371;985.1084768284713;993.1707024634367;996.038822536256;1003.4280460137797;9756.707298781048;11076.667699969013;11239.55041798319;11240.325580465198;11242.381669008282;441390;450348;470947;472593;476111;56166;58181;62790.37209627845;74028.07775377957;90610.54694726616;1001.2990906365428;1014.6550172941088;1017.5014243021014;1019.4189312092036;1030.5568605499707;16714.832851671326;17231.82768172302;17582.91208543949;17638.029908355165;17936.282548697905;37005.25989480249;38717;39330.99999999886;41086.38370424386;43369.56630433655;54883.72558137184;55617.443825561226;57118.42881571131;57235.713821430625;57498.7125064372;8682.348823838363;9130.223930965942;9157.49633770149;9343.065693430874;9361.485118318558;12430.192037517532;13648.112872663245;13679.86320136786;13980.300984950509;14159.079659822079;8813.867791982997;11943.82084268754;12038;12144.75710485803;12681.044087219496;11896.107791915814;12145.574904878287;12184.817227741414;12480.937595311965;12523.812142818048;12107.213031152947;12409.503619855104;12413.565525206574;12430.316332601793;12588.748225035264;8623.913760862311;8725.91274087251;8897.599608017785;9009.189172974464;9022.909770902206;11931.76136477283;12342.81485777696;12412.751744965231;12495.625131246077;12522 09:26:55 INFO - PROCESS | 13324 | |3;mootools.html;1566.2482008635855;1594.8325425830287;1754.1667707838778;1850.546616079059;1861.525311045683;8018.438709290352;8433.19884610961;8491.745247642566;9059.682911098113;9095.727128186156;8503.872441913376;8656.225473777877;8786.956065219665;8823.691170809023;8826.735197944072;2346.178837406911;2381.7261014983324;2393.114547617375;2397.4006498375406;2398.4123889646976;2296.024189719371;2315.5021670340875;2369.431336479241;2376.702912135983;2381.880905954704;2328.39461739948;2369.407648087978;2375.4536456614987;2378.464845409778;2388.7252965908883;2269.7503274639776;2273.818094552448;2280.635098384259;2286.4855407533255;2306.988465057681;2305.3660243433023;2307.7692230776956;2307.826912981533;2308.2959697292317;2311.3874823171873;8885.911140888638;8887.73336799897;8930.955345223298;8954.417962832395;8972.10278972104;5244.187150991603;5260.28986086881;5357.116075847481;5380.515753582196;5436.293281873332;3445.0870519312402;3463.480477928306;3484.4250698634696;3555.7333200010103;3596.4425514045365;1129.7119234595125;1133.3086817041599;1135.7047167736366;1141.3380239461092;1144.1133121830565;11835.467403966843;11927.522899084113;11973.102017348734;12032.759344813143;12107.757844843141;4610.66290775678;4650.790714417859;4712.50518695532;4812.831550895772;4814.181589129857;10214.285000050073;10277.280590358752;10286.588536458608;10293.176545876311;10297.639582614573;5201.505856943584;5280.049591073567;5327.7336133193185;5336.146216605324;5337.466253374709;4410.779461027;4680.882977925511;4753.691010084333;4770.141374552544;4883.902321953612;4100.097978444737;4160;4167.083241686824;4201.810918508674;4241.893952651147;1245.7633049720523;1250.3185763758772;1257.8364812574307;1272.0523210208453;1288.2688699955966;830.2403300979656;952.5046975572664;1019.8419245017034;1031.1647565471992;1040.552562398176;6021.187139736167;6060.030395136845;6137.754489820357;6167.321594624587;6326.398992095744;444.58653452289036;447.18612125931145;450.67326188513067;453.42415132781184;455.6992385025867;4004.599540045972;4065.7763822989427;4072.572379900127;4109.157622687342;4131.628153466202;10637.787244255072;11033.613823516138;11052.668419947415;11153.776924461465;11206.495707693173;10932.180086493545;10934.890651093547;11028.944855275673;11046.723831904106;11057.834132488018;7915.366770658334;8273.131321211307;8281.420300579022;8358.164183581594;8463.66145354179;5844.211031510692;6029.8191054268445;6084.939150608438;6097.451229389376;6127.448529632355;10065.899341006496;10212.489375531046;10371.740706482398;10561.313514621525;10614.257002010094;10311.948440258051;10504.47477626131;10509.632162874568;10599.099076578268;10701.892981070401;7315.487915846053;7701.191374905554;7887.250711182429;7920.287174154354;8032.718854840053;8693.739187824376;8758.82482350362;8775.517346546001;8800.647974081221;8829.337799664921;7523.88714169277;7584.241575842371;7600.695972161273;7777.416693748105;8064.274215320649;7520.398368130646;7592.278733520307;7645.350145237717;7653.57905315213;7760.534367937942;7086.78739637791;7127.287271272831;7157.355837974607;7158.212596614472;7179.641017949186;5097.592192624507;5134.948650513447;5202.505761952609;5232.529072383504;5290.285811415563;5321.680699158062;5372.650777699437;5410.107332290128;5483.2049352844415;5495.972520137374 09:26:55 INFO - PROCESS | 13324 | |4;prototype.html;351.53597251627855;354.7109106078546;358.12438587653196;359.85246049119866;360.50790670734455;5628.789810190809;5659.632123911948;5684.516816070629;5691.573132015105;5696.401877802833;5557.472040156189;5576.191452239423;5596.720163991805;5605.859853503664;5625.324961004675;4260.275753121977;4399.64802815774;4408.669349798762;4423.115376924612;4439.334099885027;4358.0848021915435;4367.388565600819;4381.715188512737;4401.273789824675;4403.647708183354;4381.145676593065;4382.737035777847;4387.956120438803;4392.582704643054;4424.115176964604;4107.691923105779;4326.545712700153;4330.675199360061;4347.978260108711;4381.992141807386;3784.3188226119287;3793.2034272802744;3803.4865293185344;3821.8280177392085;3855.4602355670227;5340.118880384732;5374.2476053352175;5390.326209223847;5435.347758268994;5446.537044351235;4944.664940466054;4987.850364489072;5002.899942001176;5019.774110165052;5040.36995375578;5777.884442311172;5786.189933409326;5804.767809287623;5806.748220996214;5831.212786273841;4214.641755450791;4216.789160541991;4221.261279276115;4234.06850492891;4251.298535741599;5779.2775903012125;5805.5065319447895;5850.455052052371;6013.699315034274;6042.244719410074;4277.593628605277;4284.1645879053285;4289.24938135825;4295.560987069304;4299.935500967488;4311.461067366579;4319.43847299849;4333.848315309012;4335.1979883721615;4380.759058251763;4779.808807647724;4840.88659608285;4915.238138088602;4948.406191257072;4959.876003099951;5795.710214489259;5796.159556864315;5811.215485909348;5865.618734782225;5867.3839246879315;1503.7894494931686;1505.396752458628;1514.386673397276;1524.6340878189155;1525.542337298806;926.9258459323238;960.3613596957945;960.9621608662627;962.129273007929;976.3458482816388;4982.975085124624;5014.323066386064;5034.597232221414;5037.219231019198;5062.013412086886;5359.758810853521;5383.192521121785;5402.756875940592;5410.1867029145415;5439.782408703687;4946.62900282473;4951.70289782607;5103.744812759347;5111.539961403491;5127.230915362726;2827.5758636204323;2854.8572571371765;2859.5853601227705;2865.097494289292;2876.1802886177757;5471.699056551848;5504.2294078829;5511.3937466878615;5513.842093160401;5544.944550554524;5392.622516423813;5712.428757124338;6218.284897236786;6219.657918814509;6224.844378890564;6256.468200203034;6267.310595834455;6275.466585340298;6285.2457705075685;6317.4630156437215;5960.5827592068135;6094.293597792625;6116.755329786759;6118.418750218722;6120.531072542647;6021.729022193924;6024.3674414187635;6032.6983650818165;6040.486558642565;6052.273727152769;6082.878342433215;6264.780732674334;6292.137077629759;6299.842503937438;6313.337099604475;4343.761093139908;4352.499462561846;4383.780810959375;4387.912241755083;4395.692301538862;6095.1771510845465;6102.694865256631;6116.847078823065;6161.229846269216;6172.22847144107;5927.851803704942;5935.495482883831;5938.168656388023;5954.962968885813;5968.373320801427;5918.112283157561;5921.407859214217;5930.555208359304;5932.228810254639;5935.436133567304;5535.418781027934;5841.5173551241405;5855;5855.3559108498885;5860.560457965585;5893.616914900518;5924.348321684525;5956.195913551615;5975.492083172979;5991.580589358703;5642.6614403135945;5750.5112065474905;5865.178874957424;5869.35437101927;5876.294844618673 09:26:55 INFO - PROCESS | 13324 | |5;yui.html;849.8597731374324;884.9999999999995;888.7289376740096;890.0609856601285;895.7715782475468;13296.66758331041;13927.955403344751;14018.719625607495;14499.782503262459;14533;13429.462821487128;13466.326683665826;13526.729465410686;13585.932070339659;13613.31933403331;701.3582571946663;828.958552072397;845.2688424512783;846.2679781988567;867.8568036274007;839.7732612194734;841.9368547358941;842.2209456252972;842.4818736477084;844.8605980013322;883.3595643158723;886.6719313853883;888.6534251641865;890.198821061047;890.8396488632044;884.703624285865;885.0485727842531;885.3492682878111;886.5345693510894;886.7073865624361;645;648.3969907985571;650.4211251985689;651.9771807986746;652.8106849013781;13658.863411365859;13683.657908552266;13712.17726936387;13732.313384330744;13744;13900.652483687887;13910.033221265565;14001.369938352798;14092.929535352258;14271;10692.03737720629;11053.502592383362;11058.723531911686;11130.16523760713;11245.437728113562;3843.385058390672;3859.8070096495344;3912.5891781363116;3919.3141200289833;4038.51537815464;53977.46022539763;53998.73000635011;56615.86768264653;56636.71681641607;56828.715856420866;10466.685999419877;10599.576016959236;10639.148868090535;10666.520006599721;10698.78602427947;20315.8984205078;20447.591048178954;20449.079791409422;20471.897640511703;20478;7983;8217.26044655972;8224.958875205586;8232.62953167109;8241.629126689313;4532.093581283756;4557.658175636774;4591.081783643284;4630.513796051433;4682.01677647691;3439.0886415100017;3597.1546686528645;3604.7296452766145;3606.098475381155;3609.5668519777796;651.5439192565128;654.6366766444669;656.3207080671577;658.8122385120217;661.110805965975;651.7523341130341;677.8000489855505;682.689376333767;690.1235431002699;693.3724978894109;8994.145556172152;9107.772305692411;9138.908610913939;9325.11411415928;9391.389559678599;660.3264670036535;664.3058004385429;670.3598063849013;675.7297081167573;698.8252936765808;4287.978560107242;4484.484284307281;4485.775711214426;4505.098980203972;4569.177548041318;54888.72555637196;55194.44805551893;62623;63723;63729;54209.457905420444;55365.72317138469;55563.72218138964;56012.439875601536;56577.43422565722;51789.99999999924;52318;52356.73821630867;52734.73632631889;53506.732466338195;9266.397684150508;9461.952690236505;9540.475273860004;9632.93125331037;9788.774295914376;32693.836530818146;32784.50823237701;32809.343813124084;32962.34075318528;34019.48970765391;41062.58937410707;43597;44528.77735611301;45110.09779804451;45205.77397112994;12086.335251560897;12175.573854915036;12261.668805136378;12272.325022123869;12471.438785254504;13661.521846735317;13776.380062897395;13870.098443601133;13949.511767088103;14006.859931400963;12094.153409261267;12536.247825130522;12648.87351126477;12833.230006199658;13252.337383130998;11763.353015584225;11848.170628055954;12389.132760706663;12752.234865908074;12819.102662813513;12064.75365564819;12218.144729868824;12396.194247373893;12525.123241373381;12916.22502649844;10055.195584353089;10105.141063009727;10237.74405639865;10392.948035259775;10485.633002844865;9715.17421019193;9996.600135994478;10079.596816127272;10114.747131321776;10135 09:26:55 INFO - PROCESS | 13324 | __end_tp_report 09:26:55 INFO - PROCESS | 13324 | __start_cc_report 09:26:55 INFO - PROCESS | 13324 | _x_x_mozilla_cycle_collect,349 09:26:55 INFO - PROCESS | 13324 | __end_cc_report 09:26:55 INFO - PROCESS | 13324 | __startTimestamp1462984015975__endTimestamp 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | ------- Summary: start ------- 09:26:55 INFO - PROCESS | 13324 | Number of tests: 6 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#0] dojo.html Cycles:165 Average:5548.83 Median:5780.16 stddev:734.80 (12.7%) stddev-sans-first:714.49 09:26:55 INFO - PROCESS | 13324 | Values: 3245.4 3250.2 3286.0 3289.2 3296.3 5617.5 5646.8 5693.6 5695.0 5706.7 5498.3 5548.5 5559.0 5619.2 5657.3 5022.5 5052.5 5176.6 5207.2 5215.0 1024.7 5021.7 5050.9 5063.2 5201.0 5090.5 5116.5 5157.3 5158.0 5217.3 5174.9 5183.2 5189.9 5230.3 5276.6 4936.0 4964.5 4965.4 4981.1 5115.3 5411.0 5418.2 5426.2 5432.7 5434.9 5880.2 5895.4 5899.5 5913.8 5955.2 5943.3 5943.9 5945.0 5955.7 5977.9 5559.7 5568.9 5640.2 5651.0 5656.5 6100.2 6158.0 6166.9 6170.2 6176.6 5911.3 5912.1 5954.6 5960.1 6012.5 6081.7 6090.4 6090.9 6094.7 6098.4 5871.9 5912.3 5944.8 6055.5 6061.3 5963.2 5969.1 5985.0 6002.2 6043.8 4489.1 4503.5 4518.6 4534.2 4563.4 3953.0 3981.9 4023.2 4028.4 4044.5 5507.1 5619.7 5648.3 5688.0 5898.8 5945.1 5997.9 5998.9 6005.2 6013.7 5608.2 5610.8 5671.4 5673.9 5688.3 5134.7 5154.6 5179.5 5185.7 5199.2 6228.4 6261.1 6269.5 6286.1 6288.5 5917.6 6036.1 6051.5 6104.1 6118.0 6137.6 6235.8 6241.4 6271.8 6283.4 6006.7 6015.7 6016.7 6023.2 6061.3 5897.2 5962.3 5984.0 6095.3 6105.3 5841.9 5872.9 5886.1 5901.0 5959.2 5861.0 5897.2 5903.0 5910.0 5918.8 5639.2 5691.9 5696.7 5740.7 5768.4 5881.6 5893.2 5897.2 5916.6 5922.3 5765.4 5774.7 5775.7 5780.2 5797.4 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#1] ext.html Cycles:180 Average:57678.82 Median:232059.39 stddev:110097.69 (47.4%) stddev-sans-first:110323.15 09:26:55 INFO - PROCESS | 13324 | Values: 604.2 652.5 657.7 671.7 675.9 43251.8 44108.6 44270.1 44312.6 47969.3 45234.5 46178.3 46190.8 46198.3 46263.5 8869.1 8999.2 9006.3 9012.1 9013.1 8827.9 8961.9 8968.3 8973.7 8975.0 8883.9 8983.8 8987.8 9020.4 9032.8 8890.2 8939.1 8942.3 8997.6 9035.0 8711.0 8761.2 8773.1 8783.4 8797.6 41367.8 41777.0 41797.0 42022.8 42044.6 3212.5 3283.4 3307.8 3381.5 3572.4 32187.8 32226.7 32572.7 32765.8 33266.8 4151.0 4159.8 4179.5 4196.9 4269.3 312564.0 312699.0 312892.0 313022.0 314302.0 32448.5 32968.0 59918.4 59965.1 60210.0 37971.8 38142.0 38164.2 38197.2 38351.2 15046.6 15057.6 15075.2 15161.2 15202.6 12450.8 12527.4 12546.8 12551.2 12600.0 8612.1 8628.0 8630.2 8645.7 8672.9 3324.4 3400.7 3400.8 3402.4 3428.6 1049.6 1105.8 1129.0 1167.9 1176.9 32835.5 32894.2 33222.0 33478.0 33595.8 1128.8 1152.6 1171.7 1225.8 1247.7 17251.2 17303.5 17321.2 17528.9 17660.9 490682.0 493082.0 493761.0 494024.0 497858.0 58546.7 59172.7 59267.4 59500.0 59927.4 1395.2 1399.1 1405.9 1406.2 1421.1 31471.7 32239.7 32939.7 33139.2 33363.5 52073.2 54981.7 55483.7 56131.4 61685.5 418771.0 420768.0 420979.0 421892.0 425168.0 44720.0 44831.1 44986.8 45195.3 45378.8 49337.0 49650.3 49933.5 50332.0 50483.2 41105.8 41740.6 42438.2 42450.8 43442.0 41061.4 42060.0 42226.8 42464.4 42472.4 36301.5 42602.0 44787.3 45225.5 45278.0 41005.0 44478.3 44600.8 45003.8 45173.5 33137.3 34383.8 34467.7 35255.1 35285.8 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#2] jquery.html Cycles:180 Average:27532.76 Median:2670.73 stddev:75795.62 (2838.0%) stddev-sans-first:75980.88 09:26:55 INFO - PROCESS | 13324 | Values: 405.1 409.3 419.4 421.0 428.5 18352.2 19810.3 19858.0 20072.4 20686.6 18884.4 19082.2 19870.8 20001.9 20079.3 5362.1 5730.3 5759.5 5760.8 5761.9 5656.4 5678.2 5730.7 5736.3 5749.2 5551.1 5659.0 5681.3 5685.0 5700.6 5521.2 5659.4 5690.2 5695.4 5711.5 5082.4 5115.4 5124.6 5146.2 5160.0 16954.6 18688.2 22903.3 22980.2 23104.0 2336.8 2416.2 2425.5 2560.9 2780.6 14734.5 15026.1 15099.2 15214.4 15553.7 1894.6 1944.1 2029.6 2047.2 2081.2 69811.7 72309.3 74764.0 76796.0 78109.6 10933.3 10949.8 11233.8 11299.2 11642.9 16179.9 16327.5 16373.3 16427.3 16435.3 8039.8 8363.9 8373.3 8470.7 8508.7 7817.0 7837.3 7914.2 7998.1 8012.0 4913.4 4934.7 5007.6 5015.7 5105.1 1976.0 1988.3 2029.0 2031.8 2094.0 916.8 964.7 1001.9 1036.4 1106.6 17934.6 19193.9 19853.7 20018.3 20092.8 980.3 985.1 993.2 996.0 1003.4 9756.7 11076.7 11239.6 11240.3 11242.4 441390.0 450348.0 470947.0 472593.0 476111.0 56166.0 58181.0 62790.4 74028.1 90610.5 1001.3 1014.7 1017.5 1019.4 1030.6 16714.8 17231.8 17582.9 17638.0 17936.3 37005.3 38717.0 39331.0 41086.4 43369.6 54883.7 55617.4 57118.4 57235.7 57498.7 8682.3 9130.2 9157.5 9343.1 9361.5 12430.2 13648.1 13679.9 13980.3 14159.1 8813.9 11943.8 12038.0 12144.8 12681.0 11896.1 12145.6 12184.8 12480.9 12523.8 12107.2 12409.5 12413.6 12430.3 12588.7 8623.9 8725.9 8897.6 9009.2 9022.9 11931.8 12342.8 12412.8 12495.6 12522.0 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#3] mootools.html Cycles:180 Average:5848.64 Median:448.93 stddev:3321.73 (739.9%) stddev-sans-first:3315.46 09:26:55 INFO - PROCESS | 13324 | Values: 1566.2 1594.8 1754.2 1850.5 1861.5 8018.4 8433.2 8491.7 9059.7 9095.7 8503.9 8656.2 8787.0 8823.7 8826.7 2346.2 2381.7 2393.1 2397.4 2398.4 2296.0 2315.5 2369.4 2376.7 2381.9 2328.4 2369.4 2375.5 2378.5 2388.7 2269.8 2273.8 2280.6 2286.5 2307.0 2305.4 2307.8 2307.8 2308.3 2311.4 8885.9 8887.7 8931.0 8954.4 8972.1 5244.2 5260.3 5357.1 5380.5 5436.3 3445.1 3463.5 3484.4 3555.7 3596.4 1129.7 1133.3 1135.7 1141.3 1144.1 11835.5 11927.5 11973.1 12032.8 12107.8 4610.7 4650.8 4712.5 4812.8 4814.2 10214.3 10277.3 10286.6 10293.2 10297.6 5201.5 5280.0 5327.7 5336.1 5337.5 4410.8 4680.9 4753.7 4770.1 4883.9 4100.1 4160.0 4167.1 4201.8 4241.9 1245.8 1250.3 1257.8 1272.1 1288.3 830.2 952.5 1019.8 1031.2 1040.6 6021.2 6060.0 6137.8 6167.3 6326.4 444.6 447.2 450.7 453.4 455.7 4004.6 4065.8 4072.6 4109.2 4131.6 10637.8 11033.6 11052.7 11153.8 11206.5 10932.2 10934.9 11028.9 11046.7 11057.8 7915.4 8273.1 8281.4 8358.2 8463.7 5844.2 6029.8 6084.9 6097.5 6127.4 10065.9 10212.5 10371.7 10561.3 10614.3 10311.9 10504.5 10509.6 10599.1 10701.9 7315.5 7701.2 7887.3 7920.3 8032.7 8693.7 8758.8 8775.5 8800.6 8829.3 7523.9 7584.2 7600.7 7777.4 8064.3 7520.4 7592.3 7645.4 7653.6 7760.5 7086.8 7127.3 7157.4 7158.2 7179.6 5097.6 5134.9 5202.5 5232.5 5290.3 5321.7 5372.7 5410.1 5483.2 5496.0 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#4] prototype.html Cycles:180 Average:4871.82 Median:5459.12 stddev:1440.57 (26.4%) stddev-sans-first:1404.09 09:26:55 INFO - PROCESS | 13324 | Values: 351.5 354.7 358.1 359.9 360.5 5628.8 5659.6 5684.5 5691.6 5696.4 5557.5 5576.2 5596.7 5605.9 5625.3 4260.3 4399.6 4408.7 4423.1 4439.3 4358.1 4367.4 4381.7 4401.3 4403.6 4381.1 4382.7 4388.0 4392.6 4424.1 4107.7 4326.5 4330.7 4348.0 4382.0 3784.3 3793.2 3803.5 3821.8 3855.5 5340.1 5374.2 5390.3 5435.3 5446.5 4944.7 4987.9 5002.9 5019.8 5040.4 5777.9 5786.2 5804.8 5806.7 5831.2 4214.6 4216.8 4221.3 4234.1 4251.3 5779.3 5805.5 5850.5 6013.7 6042.2 4277.6 4284.2 4289.2 4295.6 4299.9 4311.5 4319.4 4333.8 4335.2 4380.8 4779.8 4840.9 4915.2 4948.4 4959.9 5795.7 5796.2 5811.2 5865.6 5867.4 1503.8 1505.4 1514.4 1524.6 1525.5 926.9 960.4 961.0 962.1 976.3 4983.0 5014.3 5034.6 5037.2 5062.0 5359.8 5383.2 5402.8 5410.2 5439.8 4946.6 4951.7 5103.7 5111.5 5127.2 2827.6 2854.9 2859.6 2865.1 2876.2 5471.7 5504.2 5511.4 5513.8 5544.9 5392.6 5712.4 6218.3 6219.7 6224.8 6256.5 6267.3 6275.5 6285.2 6317.5 5960.6 6094.3 6116.8 6118.4 6120.5 6021.7 6024.4 6032.7 6040.5 6052.3 6082.9 6264.8 6292.1 6299.8 6313.3 4343.8 4352.5 4383.8 4387.9 4395.7 6095.2 6102.7 6116.8 6161.2 6172.2 5927.9 5935.5 5938.2 5955.0 5968.4 5918.1 5921.4 5930.6 5932.2 5935.4 5535.4 5841.5 5855.0 5855.4 5860.6 5893.6 5924.3 5956.2 5975.5 5991.6 5642.7 5750.5 5865.2 5869.4 5876.3 09:26:55 INFO - PROCESS | 13324 | 09:26:55 INFO - PROCESS | 13324 | [#5] yui.html Cycles:180 Average:14786.26 Median:45157.94 stddev:17095.35 (37.9%) stddev-sans-first:17111.27 09:26:55 INFO - PROCESS | 13324 | Values: 849.9 885.0 888.7 890.1 895.8 13296.7 13928.0 14018.7 14499.8 14533.0 13429.5 13466.3 13526.7 13585.9 13613.3 701.4 829.0 845.3 846.3 867.9 839.8 841.9 842.2 842.5 844.9 883.4 886.7 888.7 890.2 890.8 884.7 885.0 885.3 886.5 886.7 645.0 648.4 650.4 652.0 652.8 13658.9 13683.7 13712.2 13732.3 13744.0 13900.7 13910.0 14001.4 14092.9 14271.0 10692.0 11053.5 11058.7 11130.2 11245.4 3843.4 3859.8 3912.6 3919.3 4038.5 53977.5 53998.7 56615.9 56636.7 56828.7 10466.7 10599.6 10639.1 10666.5 10698.8 20315.9 20447.6 20449.1 20471.9 20478.0 7983.0 8217.3 8225.0 8232.6 8241.6 4532.1 4557.7 4591.1 4630.5 4682.0 3439.1 3597.2 3604.7 3606.1 3609.6 651.5 654.6 656.3 658.8 661.1 651.8 677.8 682.7 690.1 693.4 8994.1 9107.8 9138.9 9325.1 9391.4 660.3 664.3 670.4 675.7 698.8 4288.0 4484.5 4485.8 4505.1 4569.2 54888.7 55194.4 62623.0 63723.0 63729.0 54209.5 55365.7 55563.7 56012.4 56577.4 51790.0 52318.0 52356.7 52734.7 53506.7 9266.4 9462.0 9540.5 9632.9 9788.8 32693.8 32784.5 32809.3 32962.3 34019.5 41062.6 43597.0 44528.8 45110.1 45205.8 12086.3 12175.6 12261.7 12272.3 12471.4 13661.5 13776.4 13870.1 13949.5 14006.9 12094.2 12536.2 12648.9 12833.2 13252.3 11763.4 11848.2 12389.1 12752.2 12819.1 12064.8 12218.1 12396.2 12525.1 12916.2 10055.2 10105.1 10237.7 10392.9 10485.6 9715.2 9996.6 10079.6 10114.7 10135.0 09:26:55 INFO - PROCESS | 13324 | -------- Summary: end -------- 09:26:55 INFO - PROCESS | 13324 | 09:26:56 INFO - TEST-INFO | 13324: exit 0 09:26:56 INFO - TEST-OK | dromaeo_css | took 1110546ms 09:26:56 INFO - TEST-START | kraken 09:26:56 INFO - operating with platform_type : linux_ 09:26:56 INFO - Initialising browser for kraken test... 09:26:56 INFO - TEST-INFO | started process 13562 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsio30i/profile http://localhost:57391/getInfo.html) 09:27:00 INFO - PROCESS | 13562 | 09:27:00 INFO - PROCESS | 13562 | (process:13629): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:27:01 INFO - PROCESS | 13562 | __metrics Screen width/height:1600/1200 09:27:01 INFO - PROCESS | 13562 | colorDepth:24 09:27:01 INFO - PROCESS | 13562 | Browser inner width/height: 1024/697 09:27:01 INFO - PROCESS | 13562 | __metrics 09:27:01 ERROR - PROCESS | 13562 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 09:27:02 INFO - TEST-INFO | 13562: exit 0 09:27:02 INFO - Browser initialized. 09:27:02 INFO - Running cycle 1/1 for kraken test... 09:27:02 INFO - TEST-INFO | started process 13680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsio30i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:04 INFO - PROCESS | 13680 | 09:27:04 INFO - PROCESS | 13680 | (process:13741): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:27:14 INFO - PROCESS | 13680 | RSS: Main: 163454976 09:27:14 INFO - PROCESS | 13680 | 09:27:17 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:18 INFO - PROCESS | 13680 | RSS: Main: 167661568 09:27:18 INFO - PROCESS | 13680 | 09:27:20 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-dft) 09:27:21 INFO - PROCESS | 13680 | RSS: Main: 167931904 09:27:21 INFO - PROCESS | 13680 | 09:27:24 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-fft) 09:27:24 INFO - PROCESS | 13680 | RSS: Main: 162668544 09:27:24 INFO - PROCESS | 13680 | 09:27:27 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57391/tests/kraken/driver.html?testName=audio-oscillator) 09:27:27 INFO - PROCESS | 13680 | RSS: Main: 163053568 09:27:27 INFO - PROCESS | 13680 | 09:27:30 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:30 INFO - PROCESS | 13680 | RSS: Main: 163745792 09:27:30 INFO - PROCESS | 13680 | 09:27:33 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:34 INFO - PROCESS | 13680 | RSS: Main: 163520512 09:27:34 INFO - PROCESS | 13680 | 09:27:37 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57391/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:38 INFO - PROCESS | 13680 | RSS: Main: 162213888 09:27:38 INFO - PROCESS | 13680 | 09:27:41 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57391/tests/kraken/driver.html?testName=json-parse-financial) 09:27:42 INFO - PROCESS | 13680 | RSS: Main: 163024896 09:27:42 INFO - PROCESS | 13680 | 09:27:43 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57391/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:44 INFO - PROCESS | 13680 | RSS: Main: 163426304 09:27:44 INFO - PROCESS | 13680 | 09:27:46 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:46 INFO - PROCESS | 13680 | RSS: Main: 163565568 09:27:46 INFO - PROCESS | 13680 | 09:27:48 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:49 INFO - PROCESS | 13680 | RSS: Main: 163323904 09:27:49 INFO - PROCESS | 13680 | 09:27:52 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:52 INFO - PROCESS | 13680 | RSS: Main: 164716544 09:27:52 INFO - PROCESS | 13680 | 09:27:55 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:56 INFO - PROCESS | 13680 | RSS: Main: 164487168 09:27:56 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | Cycle 1(1): loaded http://localhost:57391/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57391/tests/kraken/driver.html?testName=ai-astar) 09:27:58 INFO - PROCESS | 13680 | RSS: Main: 165679104 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | __start_tp_report 09:27:58 INFO - PROCESS | 13680 | _x_x_mozilla_page_load 09:27:58 INFO - PROCESS | 13680 | _x_x_mozilla_page_load_details 09:27:58 INFO - PROCESS | 13680 | |i|pagename|runs| 09:27:58 INFO - PROCESS | 13680 | |0;ai-astar;107;112;104;110;113;113;106;110;113;112 09:27:58 INFO - PROCESS | 13680 | |1;audio-beat-detection;160;123;150;208;125;158;149;126;177;126 09:27:58 INFO - PROCESS | 13680 | |2;audio-dft;163;166;164;161;164;168;169;165;163;163 09:27:58 INFO - PROCESS | 13680 | |3;audio-fft;75;75;75;172;75;76;75;75;168;77 09:27:58 INFO - PROCESS | 13680 | |4;audio-oscillator;95;93;187;92;93;92;148;91;93;146 09:27:58 INFO - PROCESS | 13680 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 09:27:58 INFO - PROCESS | 13680 | |6;imaging-darkroom;146;148;146;147;147;147;147;146;147;146 09:27:58 INFO - PROCESS | 13680 | |7;imaging-desaturate;95;95;96;96;96;96;95;96;95;96 09:27:58 INFO - PROCESS | 13680 | |8;json-parse-financial;59;61;59;61;60;66;60;110;57;58 09:27:58 INFO - PROCESS | 13680 | |9;json-stringify-tinderbox;58;58;59;58;59;59;58;57;58;60 09:27:58 INFO - PROCESS | 13680 | |10;stanford-crypto-aes;78;77;88;80;79;79;85;85;79;84 09:27:58 INFO - PROCESS | 13680 | |11;stanford-crypto-ccm;125;123;128;122;125;121;126;133;202;128 09:27:58 INFO - PROCESS | 13680 | |12;stanford-crypto-pbkdf2;170;169;170;164;173;166;174;168;165;164 09:27:58 INFO - PROCESS | 13680 | |13;stanford-crypto-sha256-iterative;68;64;67;62;63;70;63;65;63;65 09:27:58 INFO - PROCESS | 13680 | __end_tp_report 09:27:58 INFO - PROCESS | 13680 | __start_cc_report 09:27:58 INFO - PROCESS | 13680 | _x_x_mozilla_cycle_collect,864 09:27:58 INFO - PROCESS | 13680 | __end_cc_report 09:27:58 INFO - PROCESS | 13680 | __startTimestamp1462984078360__endTimestamp 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | ------- Summary: start ------- 09:27:58 INFO - PROCESS | 13680 | Number of tests: 14 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#0] ai-astar Cycles:10 Average:110.00 Median:112.00 stddev:3.27 (2.9%) stddev-sans-first:3.28 09:27:58 INFO - PROCESS | 13680 | Values: 107.0 112.0 104.0 110.0 113.0 113.0 106.0 110.0 113.0 112.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:154.00 stddev:27.41 (17.8%) stddev-sans-first:28.85 09:27:58 INFO - PROCESS | 13680 | Values: 160.0 123.0 150.0 208.0 125.0 158.0 149.0 126.0 177.0 126.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#2] audio-dft Cycles:10 Average:164.60 Median:164.50 stddev:2.46 (1.5%) stddev-sans-first:2.54 09:27:58 INFO - PROCESS | 13680 | Values: 163.0 166.0 164.0 161.0 164.0 168.0 169.0 165.0 163.0 163.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:39.91 (53.2%) stddev-sans-first:41.72 09:27:58 INFO - PROCESS | 13680 | Values: 75.0 75.0 75.0 172.0 75.0 76.0 75.0 75.0 168.0 77.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#4] audio-oscillator Cycles:10 Average:113.00 Median:92.50 stddev:34.45 (37.2%) stddev-sans-first:35.92 09:27:58 INFO - PROCESS | 13680 | Values: 95.0 93.0 187.0 92.0 93.0 92.0 148.0 91.0 93.0 146.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:27:58 INFO - PROCESS | 13680 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:27:58 INFO - PROCESS | 13680 | Values: 146.0 148.0 146.0 147.0 147.0 147.0 147.0 146.0 147.0 146.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:27:58 INFO - PROCESS | 13680 | Values: 95.0 95.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:60.00 stddev:15.96 (26.6%) stddev-sans-first:16.78 09:27:58 INFO - PROCESS | 13680 | Values: 59.0 61.0 59.0 61.0 60.0 66.0 60.0 110.0 57.0 58.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.50 stddev:0.84 (1.4%) stddev-sans-first:0.88 09:27:58 INFO - PROCESS | 13680 | Values: 58.0 58.0 59.0 58.0 59.0 59.0 58.0 57.0 58.0 60.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:82.00 stddev:3.75 (4.6%) stddev-sans-first:3.77 09:27:58 INFO - PROCESS | 13680 | Values: 78.0 77.0 88.0 80.0 79.0 79.0 85.0 85.0 79.0 84.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:127.00 stddev:24.39 (19.2%) stddev-sans-first:25.68 09:27:58 INFO - PROCESS | 13680 | Values: 125.0 123.0 128.0 122.0 125.0 121.0 126.0 133.0 202.0 128.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:169.50 stddev:3.56 (2.1%) stddev-sans-first:3.72 09:27:58 INFO - PROCESS | 13680 | Values: 170.0 169.0 170.0 164.0 173.0 166.0 174.0 168.0 165.0 164.0 09:27:58 INFO - PROCESS | 13680 | 09:27:58 INFO - PROCESS | 13680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:2.58 (4.0%) stddev-sans-first:2.50 09:27:58 INFO - PROCESS | 13680 | Values: 68.0 64.0 67.0 62.0 63.0 70.0 63.0 65.0 63.0 65.0 09:27:58 INFO - PROCESS | 13680 | -------- Summary: end -------- 09:27:58 INFO - PROCESS | 13680 | 09:27:59 INFO - TEST-INFO | 13680: exit 0 09:27:59 INFO - TEST-OK | kraken | took 62575ms 09:27:59 INFO - SUITE-END | took 1173s 09:27:59 INFO - Completed test suite (00:19:33) 09:27:59 INFO - javascript benchmark 09:27:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.350929814037, 3250.236194494295, 3285.983570082149, 3289.194147433879, 3296.2583418730787, 5617.466340697637, 5646.774129034834, 5693.572982026349, 5695.0, 5706.65760054397, 5498.257735205756, 5548.500634942854, 5559.0, 5619.241402410673, 5657.2928383952, 5022.547970682647, 5052.545270925616, 5176.637635365526, 5207.192885102815, 5214.979472316329, 1024.6706214962578, 5021.723805190732, 5050.898982020358, 5063.16457784467, 5200.973995130019, 5090.54185123337, 5116.462771408986, 5157.329547158883, 5158.045761534109, 5217.2695822584865, 5174.870628234286, 5183.222516622537, 5189.948100518985, 5230.267762513251, 5276.551493123089, 4935.9753201234125, 4964.453910069926, 4965.379327584052, 4981.12830254704, 5115.309433226504, 5410.972945135289, 5418.241446197536, 5426.21319908615, 5432.674039557639, 5434.8913021739345, 5880.206172166745, 5895.410458954113, 5899.46904778572, 5913.822585322448, 5955.225820643332, 5943.257092863392, 5943.940560594382, 5945.048792193272, 5955.731992060368, 5977.910331345033, 5559.749811258463, 5568.916466253009, 5640.153976903456, 5650.971745141248, 5656.462636049569, 6100.187591190193, 6158.0, 6166.876662466815, 6170.228721409824, 6176.567640265228, 5911.320198177179, 5912.113183022495, 5954.612950158225, 5960.135780311831, 6012.549058820606, 6081.726322315506, 6090.36051214625, 6090.878182436327, 6094.695265236721, 6098.35967223444, 5871.853203669943, 5912.3496415394275, 5944.821655350346, 6055.515558755289, 6061.272647282354, 5963.165156878041, 5969.104634304889, 5985.012472941916, 6002.159697642333, 6043.8489037773525, 4489.0572979674635, 4503.527129651405, 4518.615917647036, 4534.183846907522, 4563.383943167696, 3953.0, 3981.8606348777653, 4023.1551374211745, 4028.3957406389277, 4044.51465824103, 5507.091329930517, 5619.719014049281, 5648.293963254593, 5688.033034384164, 5898.764049437975, 5945.108233764883, 5997.910031349533, 5998.940010599926, 6005.249343832021, 6013.729382177812, 5608.1587761835235, 5610.831675049755, 5671.376148623648, 5673.943260567342, 5688.288963879515, 5134.743262836918, 5154.613403994716, 5179.45615710345, 5185.688858668492, 5199.194124910645, 6228.377162283736, 6261.123442718023, 6269.529785266123, 6286.088517164985, 6288.52836037299, 5917.615355001911, 6036.094585812163, 6051.485623721945, 6104.053871649901, 6118.0, 6137.591499645514, 6235.75056997715, 6241.375862413723, 6271.843203919939, 6283.37166283368, 6006.669633170043, 6015.699215039318, 6016.729247183975, 6023.1567580537885, 6061.272647282354, 5897.174395584536, 5962.344142144448, 5983.970080149571, 6095.299040610386, 6105.297890742621, 5841.94158058431, 5872.941270587239, 5886.05823068307, 5900.970495147497, 5959.1657167995645, 5860.970695146497, 5897.203877476486, 5902.970485147718, 5909.970450147722, 5918.7928422505, 5639.154126880837, 5691.8861622768145, 5696.743646535999, 5740.712964351849, 5768.365479797303, 5881.588288819913, 5893.233879595625, 5897.233359663388, 5916.645001299936, 5922.348541660328, 5765.394633563583, 5774.711264436845, 5775.711214439178, 5780.161876527964, 5797.420257974169], "value": 5500.145280649861, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.1873679900532, 652.5497406789317, 657.7007461604967, 671.7413795688658, 675.8580698053408, 43251.783741081286, 44108.558914410845, 44270.11459770802, 44312.55687443125, 47969.28046079303, 45234.54765452344, 46178.307325390146, 46190.769046154724, 46198.30702539456, 46263.53736462635, 8869.113088691143, 8999.190072893438, 9006.32452566057, 9012.143846334588, 9013.098690131, 8827.911720882774, 8961.910380896205, 8968.327375446836, 8973.685920992766, 8975.0, 8883.866741998876, 8983.820323593556, 8987.820243595124, 9020.368574199809, 9032.819343613124, 8890.155435233642, 8939.106089391074, 8942.329325300596, 8997.550122493914, 9034.954825225897, 8711.0417854036, 8761.167689069529, 8773.078826723166, 8783.38516303859, 8797.560121993938, 41367.7931610343, 41777.0, 41797.0, 42022.78988605068, 42044.57955420437, 3212.4860022396306, 3283.3761585298957, 3307.751918606114, 3381.458966565338, 3572.392693242155, 32187.83906080478, 32226.6777332226, 32572.674273257202, 32765.83617081923, 33266.83366583152, 4151.0, 4159.7920103994675, 4179.498460184797, 4196.9370459443135, 4269.295566231568, 312564.0, 312699.0, 312892.0, 313022.0, 314302.0, 32448.513272300934, 32968.0, 59918.400815991285, 59965.10052349218, 60210.0, 37971.81014094912, 38142.0, 38164.236715266095, 38197.236055278736, 38351.23297534034, 15046.623834404227, 15057.623559411102, 15075.170865602497, 15161.241937903062, 15202.619934501727, 12450.75098498025, 12527.373631318398, 12546.81179782304, 12551.184173028723, 12600.0, 8612.095729948265, 8627.95686021566, 8630.180132887366, 8645.654173833102, 8672.913270867337, 3324.384988777084, 3400.6599340065795, 3400.812955287483, 3402.3535528249554, 3428.63999280072, 1049.58541376156, 1105.8009558279587, 1128.9500764289126, 1167.9488460385721, 1176.8999635031118, 32835.50746738801, 32894.17764555905, 33222.0, 33478.0, 33595.83202084023, 1128.8363187337955, 1152.6484422251294, 1171.6953592065954, 1225.7854875396947, 1247.732753981386, 17251.223694933506, 17303.480895572902, 17321.220545075503, 17528.91235543814, 17660.911695441442, 490682.0, 493082.0, 493761.0, 494024.0, 497858.0, 58546.707266463396, 59172.70413647904, 59267.40732592705, 59500.0, 59927.400725992185, 1395.169873925013, 1399.0766094377661, 1405.8523854995283, 1406.184413040434, 1421.0834012062369, 31471.68528314779, 32239.677603224605, 32939.67060329462, 33139.171520712174, 33363.49954750632, 52073.21890171574, 54981.72509137429, 55483.722581386835, 56131.43868561262, 61685.53257869635, 418771.0, 420768.0, 420979.0, 421892.0, 425168.0, 44720.0, 44831.103377931606, 44986.77506612446, 45195.32207016832, 45378.77310613426, 49337.0, 49650.255246170615, 49933.500664992884, 50332.0, 50483.242751358026, 41105.79447102745, 41740.58259417367, 42438.151236975704, 42450.78774606107, 43442.0, 41061.38407923943, 42060.0, 42226.788866055475, 42464.36303455512, 42472.36291455692, 36301.45547816838, 42602.0, 44787.32819007652, 45225.54774452213, 45278.0, 41005.0, 44478.332825007004, 44600.776996114815, 45003.77498112489, 45173.54826451825, 33137.33725325528, 34383.828080859435, 34467.655323446445, 35255.11862203466, 35285.82357088301], "value": 19551.210371405872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.13099401783194, 409.28171059790066, 419.4149161919122, 420.9557996410377, 428.4644194756554, 18352.174152163152, 19810.302551017874, 19858.0, 20072.397828065146, 20686.586268274626, 18884.43346699601, 19082.236710531597, 19870.801291987074, 20001.899990500064, 20079.297224597143, 5362.115250983593, 5730.3123625165035, 5759.452851979056, 5760.7695692172265, 5761.9135712964335, 5656.406077361879, 5678.233438485813, 5730.656160630376, 5736.282964629421, 5749.223854779593, 5551.139573366135, 5659.037963546187, 5681.261436013333, 5684.971575142118, 5700.572457065716, 5521.171824226358, 5659.40576239493, 5690.2033715279895, 5695.401982791809, 5711.485966263015, 5082.390113186404, 5115.411727651293, 5124.641275110744, 5146.150885103953, 5160.0, 16954.576135596584, 18688.159032843556, 22903.312900613008, 22980.19569315066, 23104.0, 2336.8013718833918, 2416.1664225842055, 2425.454272788626, 2560.8591527465983, 2780.593772628534, 14734.4842930498, 15026.098434093989, 15099.169545674984, 15214.391424343014, 15553.688926221525, 1894.6328712318102, 1944.0646364201368, 2029.5839352932614, 2047.2118234479683, 2081.2299449203742, 69811.65094174497, 72309.27690723079, 74764.0, 76796.0, 78109.60945195238, 10933.289336193122, 10949.83575246372, 11233.831492527619, 11299.20905536621, 11642.88357116418, 16179.919100404422, 16327.510174694778, 16373.263203155886, 16427.260773265232, 16435.342586296654, 8039.839203215903, 8363.916360836436, 8373.288270496954, 8470.745877623558, 8508.702195423131, 7817.022872140982, 7837.294643482114, 7914.24814642608, 7998.12020677725, 8011.959940200261, 4913.410390753133, 4934.703917764946, 5007.6494645375205, 5015.749212539358, 5105.136625131874, 1976.0, 1988.3239698502289, 2029.0, 2031.7765045844944, 2094.0, 916.7937214126769, 964.6961207219704, 1001.9348742331725, 1036.409246729357, 1106.5573770491867, 17934.5516362092, 19193.90403047976, 19853.702194467096, 20018.299359522345, 20092.799072009093, 980.3186785184371, 985.1084768284713, 993.1707024634367, 996.038822536256, 1003.4280460137797, 9756.707298781048, 11076.667699969013, 11239.55041798319, 11240.325580465198, 11242.381669008282, 441390.0, 450348.0, 470947.0, 472593.0, 476111.0, 56166.0, 58181.0, 62790.37209627845, 74028.07775377957, 90610.54694726616, 1001.2990906365428, 1014.6550172941088, 1017.5014243021014, 1019.4189312092036, 1030.5568605499707, 16714.832851671326, 17231.82768172302, 17582.91208543949, 17638.029908355165, 17936.282548697905, 37005.25989480249, 38717.0, 39330.99999999886, 41086.38370424386, 43369.56630433655, 54883.72558137184, 55617.443825561226, 57118.42881571131, 57235.713821430625, 57498.7125064372, 8682.348823838363, 9130.223930965942, 9157.49633770149, 9343.065693430874, 9361.485118318558, 12430.192037517532, 13648.112872663245, 13679.86320136786, 13980.300984950509, 14159.079659822079, 8813.867791982997, 11943.82084268754, 12038.0, 12144.75710485803, 12681.044087219496, 11896.107791915814, 12145.574904878287, 12184.817227741414, 12480.937595311965, 12523.812142818048, 12107.213031152947, 12409.503619855104, 12413.565525206574, 12430.316332601793, 12588.748225035264, 8623.913760862311, 8725.91274087251, 8897.599608017785, 9009.189172974464, 9022.909770902206, 11931.76136477283, 12342.81485777696, 12412.751744965231, 12495.625131246077, 12522.0], "value": 9317.491598076069, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1566.2482008635855, 1594.8325425830287, 1754.1667707838778, 1850.546616079059, 1861.525311045683, 8018.438709290352, 8433.19884610961, 8491.745247642566, 9059.682911098113, 9095.727128186156, 8503.872441913376, 8656.225473777877, 8786.956065219665, 8823.691170809023, 8826.735197944072, 2346.178837406911, 2381.7261014983324, 2393.114547617375, 2397.4006498375406, 2398.4123889646976, 2296.024189719371, 2315.5021670340875, 2369.431336479241, 2376.702912135983, 2381.880905954704, 2328.39461739948, 2369.407648087978, 2375.4536456614987, 2378.464845409778, 2388.7252965908883, 2269.7503274639776, 2273.818094552448, 2280.635098384259, 2286.4855407533255, 2306.988465057681, 2305.3660243433023, 2307.7692230776956, 2307.826912981533, 2308.2959697292317, 2311.3874823171873, 8885.911140888638, 8887.73336799897, 8930.955345223298, 8954.417962832395, 8972.10278972104, 5244.187150991603, 5260.28986086881, 5357.116075847481, 5380.515753582196, 5436.293281873332, 3445.0870519312402, 3463.480477928306, 3484.4250698634696, 3555.7333200010103, 3596.4425514045365, 1129.7119234595125, 1133.3086817041599, 1135.7047167736366, 1141.3380239461092, 1144.1133121830565, 11835.467403966843, 11927.522899084113, 11973.102017348734, 12032.759344813143, 12107.757844843141, 4610.66290775678, 4650.790714417859, 4712.50518695532, 4812.831550895772, 4814.181589129857, 10214.285000050073, 10277.280590358752, 10286.588536458608, 10293.176545876311, 10297.639582614573, 5201.505856943584, 5280.049591073567, 5327.7336133193185, 5336.146216605324, 5337.466253374709, 4410.779461027, 4680.882977925511, 4753.691010084333, 4770.141374552544, 4883.902321953612, 4100.097978444737, 4160.0, 4167.083241686824, 4201.810918508674, 4241.893952651147, 1245.7633049720523, 1250.3185763758772, 1257.8364812574307, 1272.0523210208453, 1288.2688699955966, 830.2403300979656, 952.5046975572664, 1019.8419245017034, 1031.1647565471992, 1040.552562398176, 6021.187139736167, 6060.030395136845, 6137.754489820357, 6167.321594624587, 6326.398992095744, 444.58653452289036, 447.18612125931145, 450.67326188513067, 453.42415132781184, 455.6992385025867, 4004.599540045972, 4065.7763822989427, 4072.572379900127, 4109.157622687342, 4131.628153466202, 10637.787244255072, 11033.613823516138, 11052.668419947415, 11153.776924461465, 11206.495707693173, 10932.180086493545, 10934.890651093547, 11028.944855275673, 11046.723831904106, 11057.834132488018, 7915.366770658334, 8273.131321211307, 8281.420300579022, 8358.164183581594, 8463.66145354179, 5844.211031510692, 6029.8191054268445, 6084.939150608438, 6097.451229389376, 6127.448529632355, 10065.899341006496, 10212.489375531046, 10371.740706482398, 10561.313514621525, 10614.257002010094, 10311.948440258051, 10504.47477626131, 10509.632162874568, 10599.099076578268, 10701.892981070401, 7315.487915846053, 7701.191374905554, 7887.250711182429, 7920.287174154354, 8032.718854840053, 8693.739187824376, 8758.82482350362, 8775.517346546001, 8800.647974081221, 8829.337799664921, 7523.88714169277, 7584.241575842371, 7600.695972161273, 7777.416693748105, 8064.274215320649, 7520.398368130646, 7592.278733520307, 7645.350145237717, 7653.57905315213, 7760.534367937942, 7086.78739637791, 7127.287271272831, 7157.355837974607, 7158.212596614472, 7179.641017949186, 5097.592192624507, 5134.948650513447, 5202.505761952609, 5232.529072383504, 5290.285811415563, 5321.680699158062, 5372.650777699437, 5410.107332290128, 5483.2049352844415, 5495.972520137374], "value": 4607.789756349676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.53597251627855, 354.7109106078546, 358.12438587653196, 359.85246049119866, 360.50790670734455, 5628.789810190809, 5659.632123911948, 5684.516816070629, 5691.573132015105, 5696.401877802833, 5557.472040156189, 5576.191452239423, 5596.720163991805, 5605.859853503664, 5625.324961004675, 4260.275753121977, 4399.64802815774, 4408.669349798762, 4423.115376924612, 4439.334099885027, 4358.0848021915435, 4367.388565600819, 4381.715188512737, 4401.273789824675, 4403.647708183354, 4381.145676593065, 4382.737035777847, 4387.956120438803, 4392.582704643054, 4424.115176964604, 4107.691923105779, 4326.545712700153, 4330.675199360061, 4347.978260108711, 4381.992141807386, 3784.3188226119287, 3793.2034272802744, 3803.4865293185344, 3821.8280177392085, 3855.4602355670227, 5340.118880384732, 5374.2476053352175, 5390.326209223847, 5435.347758268994, 5446.537044351235, 4944.664940466054, 4987.850364489072, 5002.899942001176, 5019.774110165052, 5040.36995375578, 5777.884442311172, 5786.189933409326, 5804.767809287623, 5806.748220996214, 5831.212786273841, 4214.641755450791, 4216.789160541991, 4221.261279276115, 4234.06850492891, 4251.298535741599, 5779.2775903012125, 5805.5065319447895, 5850.455052052371, 6013.699315034274, 6042.244719410074, 4277.593628605277, 4284.1645879053285, 4289.24938135825, 4295.560987069304, 4299.935500967488, 4311.461067366579, 4319.43847299849, 4333.848315309012, 4335.1979883721615, 4380.759058251763, 4779.808807647724, 4840.88659608285, 4915.238138088602, 4948.406191257072, 4959.876003099951, 5795.710214489259, 5796.159556864315, 5811.215485909348, 5865.618734782225, 5867.3839246879315, 1503.7894494931686, 1505.396752458628, 1514.386673397276, 1524.6340878189155, 1525.542337298806, 926.9258459323238, 960.3613596957945, 960.9621608662627, 962.129273007929, 976.3458482816388, 4982.975085124624, 5014.323066386064, 5034.597232221414, 5037.219231019198, 5062.013412086886, 5359.758810853521, 5383.192521121785, 5402.756875940592, 5410.1867029145415, 5439.782408703687, 4946.62900282473, 4951.70289782607, 5103.744812759347, 5111.539961403491, 5127.230915362726, 2827.5758636204323, 2854.8572571371765, 2859.5853601227705, 2865.097494289292, 2876.1802886177757, 5471.699056551848, 5504.2294078829, 5511.3937466878615, 5513.842093160401, 5544.944550554524, 5392.622516423813, 5712.428757124338, 6218.284897236786, 6219.657918814509, 6224.844378890564, 6256.468200203034, 6267.310595834455, 6275.466585340298, 6285.2457705075685, 6317.4630156437215, 5960.5827592068135, 6094.293597792625, 6116.755329786759, 6118.418750218722, 6120.531072542647, 6021.729022193924, 6024.3674414187635, 6032.6983650818165, 6040.486558642565, 6052.273727152769, 6082.878342433215, 6264.780732674334, 6292.137077629759, 6299.842503937438, 6313.337099604475, 4343.761093139908, 4352.499462561846, 4383.780810959375, 4387.912241755083, 4395.692301538862, 6095.1771510845465, 6102.694865256631, 6116.847078823065, 6161.229846269216, 6172.22847144107, 5927.851803704942, 5935.495482883831, 5938.168656388023, 5954.962968885813, 5968.373320801427, 5918.112283157561, 5921.407859214217, 5930.555208359304, 5932.228810254639, 5935.436133567304, 5535.418781027934, 5841.5173551241405, 5855.0, 5855.3559108498885, 5860.560457965585, 5893.616914900518, 5924.348321684525, 5956.195913551615, 5975.492083172979, 5991.580589358703, 5642.6614403135945, 5750.5112065474905, 5865.178874957424, 5869.35437101927, 5876.294844618673], "value": 4414.584984802596, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [849.8597731374324, 884.9999999999995, 888.7289376740096, 890.0609856601285, 895.7715782475468, 13296.66758331041, 13927.955403344751, 14018.719625607495, 14499.782503262459, 14533.0, 13429.462821487128, 13466.326683665826, 13526.729465410686, 13585.932070339659, 13613.31933403331, 701.3582571946663, 828.958552072397, 845.2688424512783, 846.2679781988567, 867.8568036274007, 839.7732612194734, 841.9368547358941, 842.2209456252972, 842.4818736477084, 844.8605980013322, 883.3595643158723, 886.6719313853883, 888.6534251641865, 890.198821061047, 890.8396488632044, 884.703624285865, 885.0485727842531, 885.3492682878111, 886.5345693510894, 886.7073865624361, 645.0, 648.3969907985571, 650.4211251985689, 651.9771807986746, 652.8106849013781, 13658.863411365859, 13683.657908552266, 13712.17726936387, 13732.313384330744, 13744.0, 13900.652483687887, 13910.033221265565, 14001.369938352798, 14092.929535352258, 14271.0, 10692.03737720629, 11053.502592383362, 11058.723531911686, 11130.16523760713, 11245.437728113562, 3843.385058390672, 3859.8070096495344, 3912.5891781363116, 3919.3141200289833, 4038.51537815464, 53977.46022539763, 53998.73000635011, 56615.86768264653, 56636.71681641607, 56828.715856420866, 10466.685999419877, 10599.576016959236, 10639.148868090535, 10666.520006599721, 10698.78602427947, 20315.8984205078, 20447.591048178954, 20449.079791409422, 20471.897640511703, 20478.0, 7983.0, 8217.26044655972, 8224.958875205586, 8232.62953167109, 8241.629126689313, 4532.093581283756, 4557.658175636774, 4591.081783643284, 4630.513796051433, 4682.01677647691, 3439.0886415100017, 3597.1546686528645, 3604.7296452766145, 3606.098475381155, 3609.5668519777796, 651.5439192565128, 654.6366766444669, 656.3207080671577, 658.8122385120217, 661.110805965975, 651.7523341130341, 677.8000489855505, 682.689376333767, 690.1235431002699, 693.3724978894109, 8994.145556172152, 9107.772305692411, 9138.908610913939, 9325.11411415928, 9391.389559678599, 660.3264670036535, 664.3058004385429, 670.3598063849013, 675.7297081167573, 698.8252936765808, 4287.978560107242, 4484.484284307281, 4485.775711214426, 4505.098980203972, 4569.177548041318, 54888.72555637196, 55194.44805551893, 62623.0, 63723.0, 63729.0, 54209.457905420444, 55365.72317138469, 55563.72218138964, 56012.439875601536, 56577.43422565722, 51789.99999999924, 52318.0, 52356.73821630867, 52734.73632631889, 53506.732466338195, 9266.397684150508, 9461.952690236505, 9540.475273860004, 9632.93125331037, 9788.774295914376, 32693.836530818146, 32784.50823237701, 32809.343813124084, 32962.34075318528, 34019.48970765391, 41062.58937410707, 43597.0, 44528.77735611301, 45110.09779804451, 45205.77397112994, 12086.335251560897, 12175.573854915036, 12261.668805136378, 12272.325022123869, 12471.438785254504, 13661.521846735317, 13776.380062897395, 13870.098443601133, 13949.511767088103, 14006.859931400963, 12094.153409261267, 12536.247825130522, 12648.87351126477, 12833.230006199658, 13252.337383130998, 11763.353015584225, 11848.170628055954, 12389.132760706663, 12752.234865908074, 12819.102662813513, 12064.75365564819, 12218.144729868824, 12396.194247373893, 12525.123241373381, 12916.22502649844, 10055.195584353089, 10105.141063009727, 10237.74405639865, 10392.948035259775, 10485.633002844865, 9715.17421019193, 9996.600135994478, 10079.596816127272, 10114.747131321776, 10135.0], "value": 6773.194308670747, "unit": "score"}], "name": "dromaeo_css", "value": 7189.149128571333, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 112.0, 104.0, 110.0, 113.0, 113.0, 106.0, 110.0, 113.0, 112.0], "value": 110.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 123.0, 150.0, 208.0, 125.0, 158.0, 149.0, 126.0, 177.0, 126.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 164.0, 161.0, 164.0, 168.0, 169.0, 165.0, 163.0, 163.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 172.0, 75.0, 76.0, 75.0, 75.0, 168.0, 77.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 187.0, 92.0, 93.0, 92.0, 148.0, 91.0, 93.0, 146.0], "value": 113.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 61.0, 60.0, 66.0, 60.0, 110.0, 57.0, 58.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 59.0, 59.0, 58.0, 57.0, 58.0, 60.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 88.0, 80.0, 79.0, 79.0, 85.0, 85.0, 79.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 123.0, 128.0, 122.0, 125.0, 121.0, 126.0, 133.0, 202.0, 128.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 170.0, 164.0, 173.0, 166.0, 174.0, 168.0, 165.0, 164.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 67.0, 62.0, 63.0, 70.0, 63.0, 65.0, 63.0, 65.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1562.9, "alertThreshold": 2.0}]} 09:27:59 INFO - Return code: 0 09:27:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:27:59 ERROR - # TBPL SUCCESS # 09:27:59 INFO - Running post-action listener: _resource_record_post_action 09:27:59 INFO - ##### 09:27:59 INFO - ##### Finished run-tests step (success) 09:27:59 INFO - ##### 09:27:59 INFO - Running post-run listener: _resource_record_post_run 09:28:00 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 540884992; Read time: 0; Write time: 499748 09:28:00 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256081920; Read time: 0; Write time: 252376 09:28:00 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 284803072; Read time: 0; Write time: 247372 09:28:00 INFO - Running post-run listener: _upload_blobber_files 09:28:00 INFO - Blob upload gear active. 09:28:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:28:00 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 09:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:28:01 INFO - (blobuploader) - INFO - Open directory for files ... 09:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:28:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:01 INFO - (blobuploader) - INFO - Done attempting. 09:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:28:02 INFO - (blobuploader) - INFO - Done attempting. 09:28:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:28:02 INFO - Return code: 0 09:28:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:28:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:28:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/616802373a800e4ec09bc869bd2d0ef5669d3c7a752b7a5357b41282676d14eade4e97238ce9c9dace634f06fca9a4da5eacf0c46015c207804580c3007edff0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/841b2811aa73be2866569bcc6fe51991a25d95c087e3fc687ac409ff3da80931d8717d27ebbabb26e4e3ea2475937d40593b2a39f2ae0427d6b0f05c1419ccbf"} 09:28:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:28:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:28:02 INFO - Contents: 09:28:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/616802373a800e4ec09bc869bd2d0ef5669d3c7a752b7a5357b41282676d14eade4e97238ce9c9dace634f06fca9a4da5eacf0c46015c207804580c3007edff0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/841b2811aa73be2866569bcc6fe51991a25d95c087e3fc687ac409ff3da80931d8717d27ebbabb26e4e3ea2475937d40593b2a39f2ae0427d6b0f05c1419ccbf"} 09:28:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:28:02 INFO - Copying logs to upload dir... 09:28:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:28:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.331586 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-11 09:28:02.703124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:28:02.707019) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/616802373a800e4ec09bc869bd2d0ef5669d3c7a752b7a5357b41282676d14eade4e97238ce9c9dace634f06fca9a4da5eacf0c46015c207804580c3007edff0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/841b2811aa73be2866569bcc6fe51991a25d95c087e3fc687ac409ff3da80931d8717d27ebbabb26e4e3ea2475937d40593b2a39f2ae0427d6b0f05c1419ccbf"} build_url:https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011410 build_url: 'https://queue.taskcluster.net/v1/task/Ja2kUphES8aukwqBa1x3DQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/616802373a800e4ec09bc869bd2d0ef5669d3c7a752b7a5357b41282676d14eade4e97238ce9c9dace634f06fca9a4da5eacf0c46015c207804580c3007edff0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/841b2811aa73be2866569bcc6fe51991a25d95c087e3fc687ac409ff3da80931d8717d27ebbabb26e4e3ea2475937d40593b2a39f2ae0427d6b0f05c1419ccbf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 09:28:02.732378) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:28:02.732713) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1462982850.363948-429695214 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 09:28:02.817440) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:28:02.817762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 09:28:02.818069) ========= ========= Total master_lag: 0.18 =========