builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1448810793.46 results: success (0) buildid: 20151129064331 builduid: 26aafcd39c5c40349bff151b66ba5f7d revision: c952179edbfc58e2e8db3df6782c943782229112 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.459129) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.459482) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.459793) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.009133 basedir: '/builds/slave/test' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.518078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.518359) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.653066) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.653393) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.034460 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.712209) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.712612) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.713095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.713565) ========= 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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-29 07:26:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 215M=0s 2015-11-29 07:26:33 (215 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.852140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.852444) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.055686 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.918103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:33.918438) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c952179edbfc58e2e8db3df6782c943782229112 --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 c952179edbfc58e2e8db3df6782c943782229112 --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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-29 07:26:34,039 truncating revision to first 12 chars 2015-11-29 07:26:34,039 Setting DEBUG logging. 2015-11-29 07:26:34,039 attempt 1/10 2015-11-29 07:26:34,039 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c952179edbfc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-29 07:26:34,456 unpacking tar archive at: fx-team-c952179edbfc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689829 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:34.621783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:34.622061) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:34.630591) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:34.630892) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-29 07:26:34.631279) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-29 07:26:34.631575) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:26:34 INFO - MultiFileLogger online at 20151129 07:26:34 in /builds/slave/test 07:26:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 07:26:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:26:34 INFO - {'append_to_log': False, 07:26:34 INFO - 'base_work_dir': '/builds/slave/test', 07:26:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 07:26:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:26:34 INFO - 'branch': 'Fx-Team-Non-PGO', 07:26:34 INFO - 'buildbot_json_path': 'buildprops.json', 07:26:34 INFO - 'config_files': ('talos/linux_config.py',), 07:26:34 INFO - 'default_actions': ('clobber', 07:26:34 INFO - 'read-buildbot-config', 07:26:34 INFO - 'download-and-extract', 07:26:34 INFO - 'populate-webroot', 07:26:34 INFO - 'create-virtualenv', 07:26:34 INFO - 'install', 07:26:34 INFO - 'run-tests'), 07:26:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:26:34 INFO - 'download_minidump_stackwalk': True, 07:26:34 INFO - 'download_symbols': 'ondemand', 07:26:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:26:34 INFO - 'tooltool.py': '/tools/tooltool.py', 07:26:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:26:34 INFO - '/tools/misc-python/virtualenv.py')}, 07:26:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:26:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:26:34 INFO - 'installer_path': 'installer.exe', 07:26:34 INFO - 'log_level': 'info', 07:26:34 INFO - 'log_name': 'talos', 07:26:34 INFO - 'log_to_console': True, 07:26:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:26:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:26:34 INFO - 'opt_config_files': (), 07:26:34 INFO - 'pip_index': False, 07:26:34 INFO - 'sps_profile': False, 07:26:34 INFO - 'sps_profile_interval': 0, 07:26:34 INFO - 'suite': 'dromaeojs-e10s', 07:26:34 INFO - 'system_bits': '64', 07:26:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:26:34 INFO - 'title': 'talos-linux64-ix-003', 07:26:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:26:34 INFO - 'use_talos_json': True, 07:26:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:26:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:26:34 INFO - 'work_dir': 'build'} 07:26:34 INFO - ##### 07:26:34 INFO - ##### Running clobber step. 07:26:34 INFO - ##### 07:26:34 INFO - Running pre-action listener: _resource_record_pre_action 07:26:34 INFO - Running main action method: clobber 07:26:34 INFO - rmtree: /builds/slave/test/build 07:26:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:26:36 INFO - Running post-action listener: _resource_record_post_action 07:26:36 INFO - ##### 07:26:36 INFO - ##### Running read-buildbot-config step. 07:26:36 INFO - ##### 07:26:36 INFO - Running pre-action listener: _resource_record_pre_action 07:26:36 INFO - Running main action method: read_buildbot_config 07:26:36 INFO - Using buildbot properties: 07:26:36 INFO - { 07:26:36 INFO - "properties": { 07:26:36 INFO - "buildnumber": 481, 07:26:36 INFO - "product": "firefox", 07:26:36 INFO - "script_repo_revision": "production", 07:26:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 07:26:36 INFO - "repository": "", 07:26:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 07:26:36 INFO - "buildid": "20151129064331", 07:26:36 INFO - "slavename": "talos-linux64-ix-003", 07:26:36 INFO - "pgo_build": "False", 07:26:36 INFO - "basedir": "/builds/slave/test", 07:26:36 INFO - "project": "", 07:26:36 INFO - "platform": "ubuntu64_hw", 07:26:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:26:36 INFO - "slavebuilddir": "test", 07:26:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 07:26:36 INFO - "branch": "fx-team", 07:26:36 INFO - "repo_path": "integration/fx-team", 07:26:36 INFO - "stage_platform": "linux64", 07:26:36 INFO - "builduid": "26aafcd39c5c40349bff151b66ba5f7d", 07:26:36 INFO - "revision": "c952179edbfc58e2e8db3df6782c943782229112" 07:26:36 INFO - }, 07:26:36 INFO - "sourcestamp": { 07:26:36 INFO - "repository": "", 07:26:36 INFO - "hasPatch": false, 07:26:36 INFO - "project": "", 07:26:36 INFO - "branch": "fx-team-linux64-talos", 07:26:36 INFO - "changes": [ 07:26:36 INFO - { 07:26:36 INFO - "category": null, 07:26:36 INFO - "files": [ 07:26:36 INFO - { 07:26:36 INFO - "url": null, 07:26:36 INFO - "name": "https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 07:26:36 INFO - } 07:26:36 INFO - ], 07:26:36 INFO - "repository": "", 07:26:36 INFO - "rev": "c952179edbfc58e2e8db3df6782c943782229112", 07:26:36 INFO - "who": "mfinkle@mozilla.com", 07:26:36 INFO - "when": 1448810749, 07:26:36 INFO - "number": 6718327, 07:26:36 INFO - "comments": "Bug 1227997 - Make theme fallback actually work r=mossop", 07:26:36 INFO - "project": "", 07:26:36 INFO - "at": "Sun 29 Nov 2015 07:25:49", 07:26:36 INFO - "branch": "fx-team-linux64-talos", 07:26:36 INFO - "revlink": "", 07:26:36 INFO - "properties": [ 07:26:36 INFO - [ 07:26:36 INFO - "buildid", 07:26:36 INFO - "20151129064331", 07:26:36 INFO - "Change" 07:26:36 INFO - ], 07:26:36 INFO - [ 07:26:36 INFO - "builduid", 07:26:36 INFO - "26aafcd39c5c40349bff151b66ba5f7d", 07:26:36 INFO - "Change" 07:26:36 INFO - ], 07:26:36 INFO - [ 07:26:36 INFO - "pgo_build", 07:26:36 INFO - "False", 07:26:36 INFO - "Change" 07:26:36 INFO - ] 07:26:36 INFO - ], 07:26:36 INFO - "revision": "c952179edbfc58e2e8db3df6782c943782229112" 07:26:36 INFO - } 07:26:36 INFO - ], 07:26:36 INFO - "revision": "c952179edbfc58e2e8db3df6782c943782229112" 07:26:36 INFO - } 07:26:36 INFO - } 07:26:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 07:26:36 INFO - Running post-action listener: _resource_record_post_action 07:26:36 INFO - ##### 07:26:36 INFO - ##### Running download-and-extract step. 07:26:36 INFO - ##### 07:26:36 INFO - Running pre-action listener: _resource_record_pre_action 07:26:36 INFO - Running main action method: download_and_extract 07:26:36 INFO - mkdir: /builds/slave/test/build/tests 07:26: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')]} 07:26:36 INFO - https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 07:26:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/test_packages.json 07:26:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/test_packages.json 07:26:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 07:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 07:26:37 INFO - Downloaded 1302 bytes. 07:26:37 INFO - Reading from file /builds/slave/test/build/test_packages.json 07:26:37 INFO - Using the following test package requirements: 07:26:37 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:26:37 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:26:37 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'jsshell-linux-x86_64.zip'], 07:26:37 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:26:37 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:26:37 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:26:37 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:26:37 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:26:37 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 07:26:37 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 07:26:37 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:26:37 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:37 INFO - https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:26:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:26:39 INFO - Downloaded 21517181 bytes. 07:26:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:26:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:26:41 INFO - Return code: 0 07:26:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:26: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')]} 07:26:41 INFO - https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:26:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:26:42 INFO - Downloaded 21517181 bytes. 07:26:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 07:26:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 07:26:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 07:26:43 INFO - Return code: 0 07:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:43 INFO - https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:26:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:26:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:26:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:26:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:26:45 INFO - Downloaded 11131728 bytes. 07:26:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 07:26:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 07:26:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 07:26:45 INFO - Return code: 0 07:26:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:45 INFO - https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:26:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:26:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:26:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:26:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:26:47 INFO - Downloaded 56871298 bytes. 07:26:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:26:47 INFO - mkdir: /builds/slave/test/properties 07:26:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:26:47 INFO - Writing to file /builds/slave/test/properties/build_url 07:26:47 INFO - Contents: 07:26:47 INFO - build_url:https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 07:26:47 INFO - Running post-action listener: _resource_record_post_action 07:26:47 INFO - Running post-action listener: set_extra_try_arguments 07:26:47 INFO - ##### 07:26:47 INFO - ##### Running populate-webroot step. 07:26:47 INFO - ##### 07:26:47 INFO - Running pre-action listener: _resource_record_pre_action 07:26:47 INFO - Running main action method: populate_webroot 07:26:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:26:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:26:47 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 07:26:47 INFO - '../fennec_ids.txt'], 07:26:47 INFO - 'tests': ['tcheck2']}, 07:26:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:26:47 INFO - '--tppagecycles', 07:26:47 INFO - '7'], 07:26:47 INFO - 'tests': ['tsvgm']}}, 07:26:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 07:26:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:26:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:26:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 07:26:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:26:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'tests': ['damp', 'tps']}, 07:26:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['damp', 'tps']}, 07:26:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 07:26:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:26:47 INFO - 'other': {'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'other_l64': {'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'other_nol64': {'tests': ['a11yr', 07:26:47 INFO - 'ts_paint', 07:26:47 INFO - 'tpaint', 07:26:47 INFO - 'sessionrestore', 07:26:47 INFO - 'sessionrestore_no_auto_restore']}, 07:26:47 INFO - 'svgr': {'tests': ['tsvgx', 07:26:47 INFO - 'tsvgr_opacity', 07:26:47 INFO - 'tart', 07:26:47 INFO - 'tscrollx', 07:26:47 INFO - 'cart']}, 07:26:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['tsvgx', 07:26:47 INFO - 'tsvgr_opacity', 07:26:47 INFO - 'tart', 07:26:47 INFO - 'tscrollx', 07:26:47 INFO - 'cart']}, 07:26:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'tests': ['tp5o']}, 07:26:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'talos_options': ['--e10s'], 07:26:47 INFO - 'tests': ['tp5o']}, 07:26:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'talos_options': ['--xperf_path', 07:26:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:47 INFO - 'tests': ['tp5n']}, 07:26:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:26:47 INFO - 'talos_options': ['--e10s', 07:26:47 INFO - '--xperf_path', 07:26:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:26:47 INFO - 'tests': ['tp5n']}}, 07:26:47 INFO - 'talos.zip': {'path': '', 07:26:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:26:47 INFO - Running post-action listener: _resource_record_post_action 07:26:47 INFO - ##### 07:26:47 INFO - ##### Running create-virtualenv step. 07:26:47 INFO - ##### 07:26:47 INFO - Running pre-action listener: _resource_record_pre_action 07:26:47 INFO - Running main action method: create_virtualenv 07:26:47 INFO - Creating virtualenv /builds/slave/test/build/venv 07:26:47 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 07:26:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:26:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:26:47 INFO - Using real prefix '/usr' 07:26:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:26:48 INFO - Installing distribute.............................................................................................................................................................................................done. 07:26:49 INFO - Installing pip.................done. 07:26:50 INFO - Return code: 0 07:26:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:26:50 INFO - Using env: {'DISPLAY': ':0', 07:26:50 INFO - 'HOME': '/home/cltbld', 07:26:50 INFO - 'LANG': 'en_US.UTF-8', 07:26:50 INFO - 'LANGUAGE': 'en_US:en', 07:26:50 INFO - 'LOGNAME': 'cltbld', 07:26:50 INFO - 'MAIL': '/var/mail/cltbld', 07:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:50 INFO - 'MOZ_NO_REMOTE': '1', 07:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:50 INFO - 'NO_EM_RESTART': '1', 07:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:50 INFO - 'PWD': '/builds/slave/test', 07:26:50 INFO - 'SHELL': '/bin/bash', 07:26:50 INFO - 'SHLVL': '1', 07:26:50 INFO - 'TERM': 'linux', 07:26:50 INFO - 'TMOUT': '86400', 07:26:50 INFO - 'USER': 'cltbld', 07:26:50 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:50 INFO - '_': '/tools/buildbot/bin/python'} 07:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:50 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 07:26:50 INFO - Cleaning up... 07:26:50 INFO - Return code: 0 07:26:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:26:50 INFO - Using env: {'DISPLAY': ':0', 07:26:50 INFO - 'HOME': '/home/cltbld', 07:26:50 INFO - 'LANG': 'en_US.UTF-8', 07:26:50 INFO - 'LANGUAGE': 'en_US:en', 07:26:50 INFO - 'LOGNAME': 'cltbld', 07:26:50 INFO - 'MAIL': '/var/mail/cltbld', 07:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:50 INFO - 'MOZ_NO_REMOTE': '1', 07:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:50 INFO - 'NO_EM_RESTART': '1', 07:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:50 INFO - 'PWD': '/builds/slave/test', 07:26:50 INFO - 'SHELL': '/bin/bash', 07:26:50 INFO - 'SHLVL': '1', 07:26:50 INFO - 'TERM': 'linux', 07:26:50 INFO - 'TMOUT': '86400', 07:26:50 INFO - 'USER': 'cltbld', 07:26:50 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:50 INFO - '_': '/tools/buildbot/bin/python'} 07:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:50 INFO - Downloading/unpacking psutil>=0.7.1 07:26:50 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 07:26:50 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 07:26:50 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 07:26:50 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 07:26:50 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 07:26:50 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 07:26:52 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 07:26:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 07:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:26:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:26:52 INFO - Installing collected packages: psutil 07:26:52 INFO - Running setup.py install for psutil 07:26:52 INFO - building 'psutil._psutil_linux' extension 07:26:52 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 07:26:52 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 07:26:52 INFO - building 'psutil._psutil_posix' extension 07:26:52 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 07:26:52 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 07:26:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:26:52 INFO - Successfully installed psutil 07:26:52 INFO - Cleaning up... 07:26:52 INFO - Return code: 0 07:26:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:26:52 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')]} 07:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:52 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')]} 07:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:26:52 INFO - Using env: {'DISPLAY': ':0', 07:26:52 INFO - 'HOME': '/home/cltbld', 07:26:52 INFO - 'LANG': 'en_US.UTF-8', 07:26:52 INFO - 'LANGUAGE': 'en_US:en', 07:26:52 INFO - 'LOGNAME': 'cltbld', 07:26:52 INFO - 'MAIL': '/var/mail/cltbld', 07:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:52 INFO - 'MOZ_NO_REMOTE': '1', 07:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:52 INFO - 'NO_EM_RESTART': '1', 07:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:52 INFO - 'PWD': '/builds/slave/test', 07:26:52 INFO - 'SHELL': '/bin/bash', 07:26:52 INFO - 'SHLVL': '1', 07:26:52 INFO - 'TERM': 'linux', 07:26:52 INFO - 'TMOUT': '86400', 07:26:52 INFO - 'USER': 'cltbld', 07:26:52 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:52 INFO - '_': '/tools/buildbot/bin/python'} 07:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:26:52 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 07:26:52 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 07:26:52 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 07:26:52 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 07:26:52 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 07:26:52 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 07:26:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:26:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 07:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:26:54 INFO - Installing collected packages: mozsystemmonitor 07:26:54 INFO - Running setup.py install for mozsystemmonitor 07:26:54 INFO - Successfully installed mozsystemmonitor 07:26:54 INFO - Cleaning up... 07:26:54 INFO - Return code: 0 07:26:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:26: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')]} 07:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26: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')]} 07:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:26:54 INFO - Using env: {'DISPLAY': ':0', 07:26:54 INFO - 'HOME': '/home/cltbld', 07:26:54 INFO - 'LANG': 'en_US.UTF-8', 07:26:54 INFO - 'LANGUAGE': 'en_US:en', 07:26:54 INFO - 'LOGNAME': 'cltbld', 07:26:54 INFO - 'MAIL': '/var/mail/cltbld', 07:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:54 INFO - 'MOZ_NO_REMOTE': '1', 07:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:54 INFO - 'NO_EM_RESTART': '1', 07:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:54 INFO - 'PWD': '/builds/slave/test', 07:26:54 INFO - 'SHELL': '/bin/bash', 07:26:54 INFO - 'SHLVL': '1', 07:26:54 INFO - 'TERM': 'linux', 07:26:54 INFO - 'TMOUT': '86400', 07:26:54 INFO - 'USER': 'cltbld', 07:26:54 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:54 INFO - '_': '/tools/buildbot/bin/python'} 07:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:55 INFO - Downloading/unpacking blobuploader==1.2.4 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:56 INFO - Downloading blobuploader-1.2.4.tar.gz 07:26:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 07:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:26:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 07:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:26:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:26:57 INFO - Downloading docopt-0.6.1.tar.gz 07:26:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 07:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:26:57 INFO - Installing collected packages: blobuploader, requests, docopt 07:26:57 INFO - Running setup.py install for blobuploader 07:26:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:26:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:26:57 INFO - Running setup.py install for requests 07:26:57 INFO - Running setup.py install for docopt 07:26:57 INFO - Successfully installed blobuploader requests docopt 07:26:57 INFO - Cleaning up... 07:26:58 INFO - Return code: 0 07:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:26: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')]} 07:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26: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')]} 07:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:26:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 07:26:58 INFO - Using env: {'DISPLAY': ':0', 07:26:58 INFO - 'HOME': '/home/cltbld', 07:26:58 INFO - 'LANG': 'en_US.UTF-8', 07:26:58 INFO - 'LANGUAGE': 'en_US:en', 07:26:58 INFO - 'LOGNAME': 'cltbld', 07:26:58 INFO - 'MAIL': '/var/mail/cltbld', 07:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:26:58 INFO - 'MOZ_NO_REMOTE': '1', 07:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:26:58 INFO - 'NO_EM_RESTART': '1', 07:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:26:58 INFO - 'PWD': '/builds/slave/test', 07:26:58 INFO - 'SHELL': '/bin/bash', 07:26:58 INFO - 'SHLVL': '1', 07:26:58 INFO - 'TERM': 'linux', 07:26:58 INFO - 'TMOUT': '86400', 07:26:58 INFO - 'USER': 'cltbld', 07:26:58 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:26:58 INFO - '_': '/tools/buildbot/bin/python'} 07:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:26:58 INFO - Running setup.py (path:/tmp/pip-LIFVMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:26:58 INFO - Running setup.py (path:/tmp/pip-V7pivI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:26:58 INFO - Running setup.py (path:/tmp/pip-twQRJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:26:58 INFO - Running setup.py (path:/tmp/pip-Q3ZhSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:26:58 INFO - Running setup.py (path:/tmp/pip-GV5gww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:26:58 INFO - Running setup.py (path:/tmp/pip-B8DTaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:26:58 INFO - Running setup.py (path:/tmp/pip-h_rGhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:26:58 INFO - Running setup.py (path:/tmp/pip-Iq3Gm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:26:58 INFO - Running setup.py (path:/tmp/pip-nltfHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:26:58 INFO - Running setup.py (path:/tmp/pip-4dr0IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:26:58 INFO - Running setup.py (path:/tmp/pip-Mw9zyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:26:59 INFO - Running setup.py (path:/tmp/pip-UCgCrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:26:59 INFO - Running setup.py (path:/tmp/pip-V8CiV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:26:59 INFO - Running setup.py (path:/tmp/pip-cSekve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:59 INFO - Running setup.py (path:/tmp/pip-zSxRhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:26:59 INFO - Running setup.py (path:/tmp/pip-W2Gm_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:26:59 INFO - Running setup.py (path:/tmp/pip-8vboxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:26:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:26:59 INFO - Running setup.py install for manifestparser 07:26:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for mozcrash 07:26:59 INFO - Running setup.py install for mozdebug 07:26:59 INFO - Running setup.py install for mozdevice 07:26:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:26:59 INFO - Running setup.py install for mozfile 07:27:00 INFO - Running setup.py install for mozhttpd 07:27:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozinfo 07:27:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozInstall 07:27:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozleak 07:27:00 INFO - Running setup.py install for mozlog 07:27:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for moznetwork 07:27:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozprocess 07:27:00 INFO - Running setup.py install for mozprofile 07:27:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozrunner 07:27:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:27:00 INFO - Running setup.py install for mozscreenshot 07:27:01 INFO - Running setup.py install for moztest 07:27:01 INFO - Running setup.py install for mozversion 07:27:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:27:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:27:01 INFO - Cleaning up... 07:27:01 INFO - Return code: 0 07:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:27:01 INFO - Using env: {'DISPLAY': ':0', 07:27:01 INFO - 'HOME': '/home/cltbld', 07:27:01 INFO - 'LANG': 'en_US.UTF-8', 07:27:01 INFO - 'LANGUAGE': 'en_US:en', 07:27:01 INFO - 'LOGNAME': 'cltbld', 07:27:01 INFO - 'MAIL': '/var/mail/cltbld', 07:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:01 INFO - 'MOZ_NO_REMOTE': '1', 07:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:01 INFO - 'NO_EM_RESTART': '1', 07:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:01 INFO - 'PWD': '/builds/slave/test', 07:27:01 INFO - 'SHELL': '/bin/bash', 07:27:01 INFO - 'SHLVL': '1', 07:27:01 INFO - 'TERM': 'linux', 07:27:01 INFO - 'TMOUT': '86400', 07:27:01 INFO - 'USER': 'cltbld', 07:27:01 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:01 INFO - '_': '/tools/buildbot/bin/python'} 07:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:27:01 INFO - Running setup.py (path:/tmp/pip-vcXFI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:27:01 INFO - Running setup.py (path:/tmp/pip-q3wSgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:27:01 INFO - Running setup.py (path:/tmp/pip-TCOExE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:27:01 INFO - Running setup.py (path:/tmp/pip-f0kMEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:27:01 INFO - Running setup.py (path:/tmp/pip-fMyXzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:27:01 INFO - Running setup.py (path:/tmp/pip-dQaYrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:27:01 INFO - Running setup.py (path:/tmp/pip-go4bPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:27:01 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)) 07:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:27:01 INFO - Running setup.py (path:/tmp/pip-XeuI1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:27:02 INFO - Running setup.py (path:/tmp/pip-j9N22u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:27:02 INFO - Running setup.py (path:/tmp/pip-FUsnCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:27:02 INFO - Running setup.py (path:/tmp/pip-W85Iv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:27:02 INFO - Running setup.py (path:/tmp/pip-pcCGPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:27:02 INFO - Running setup.py (path:/tmp/pip-uVARE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:27:02 INFO - Running setup.py (path:/tmp/pip-qWio13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:02 INFO - Running setup.py (path:/tmp/pip-7svN9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:27:02 INFO - Running setup.py (path:/tmp/pip-p_S_AD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:27:02 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)) 07:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:27:02 INFO - Running setup.py (path:/tmp/pip-NTY0uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:27:02 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)) 07:27:02 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:02 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:27:02 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)) 07:27:02 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:02 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:27:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27:04 INFO - Downloading blessings-1.5.1.tar.gz 07:27:04 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 07:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:27:04 INFO - Installing collected packages: blessings 07:27:04 INFO - Running setup.py install for blessings 07:27:04 INFO - Successfully installed blessings 07:27:04 INFO - Cleaning up... 07:27:04 INFO - Return code: 0 07:27:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:04 INFO - Reading from file tmpfile_stdout 07:27:04 INFO - Current package versions: 07:27:04 INFO - argparse == 1.2.1 07:27:04 INFO - blessings == 1.5.1 07:27:04 INFO - blobuploader == 1.2.4 07:27:04 INFO - docopt == 0.6.1 07:27:04 INFO - manifestparser == 1.1 07:27:04 INFO - mozInstall == 1.12 07:27:04 INFO - mozcrash == 0.16 07:27:04 INFO - mozdebug == 0.1 07:27:04 INFO - mozdevice == 0.47 07:27:04 INFO - mozfile == 1.2 07:27:04 INFO - mozhttpd == 0.7 07:27:04 INFO - mozinfo == 0.9 07:27:04 INFO - mozleak == 0.1 07:27:04 INFO - mozlog == 3.1 07:27:04 INFO - moznetwork == 0.27 07:27:04 INFO - mozprocess == 0.22 07:27:04 INFO - mozprofile == 0.27 07:27:04 INFO - mozrunner == 6.11 07:27:04 INFO - mozscreenshot == 0.1 07:27:04 INFO - mozsystemmonitor == 0.0 07:27:04 INFO - moztest == 0.7 07:27:04 INFO - mozversion == 1.4 07:27:04 INFO - psutil == 3.1.1 07:27:04 INFO - requests == 1.2.3 07:27:04 INFO - wsgiref == 0.1.2 07:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:27: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')]} 07:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27: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')]} 07:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:27:04 INFO - Using env: {'DISPLAY': ':0', 07:27:04 INFO - 'HOME': '/home/cltbld', 07:27:04 INFO - 'LANG': 'en_US.UTF-8', 07:27:04 INFO - 'LANGUAGE': 'en_US:en', 07:27:04 INFO - 'LOGNAME': 'cltbld', 07:27:04 INFO - 'MAIL': '/var/mail/cltbld', 07:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:04 INFO - 'MOZ_NO_REMOTE': '1', 07:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:04 INFO - 'NO_EM_RESTART': '1', 07:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:04 INFO - 'PWD': '/builds/slave/test', 07:27:04 INFO - 'SHELL': '/bin/bash', 07:27:04 INFO - 'SHLVL': '1', 07:27:04 INFO - 'TERM': 'linux', 07:27:04 INFO - 'TMOUT': '86400', 07:27:04 INFO - 'USER': 'cltbld', 07:27:04 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:04 INFO - '_': '/tools/buildbot/bin/python'} 07:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:05 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 1)) 07:27:05 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 2)) 07:27:05 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 3)) 07:27:05 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 4)) 07:27:05 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 5)) 07:27:05 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 6)) 07:27:05 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 7)) 07:27:05 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 8)) 07:27:05 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:05 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 3)) 07:27:05 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:27:05 INFO - Cleaning up... 07:27:05 INFO - Return code: 0 07:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:27:05 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')]} 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 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')]} 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:27:05 INFO - Using env: {'DISPLAY': ':0', 07:27:05 INFO - 'HOME': '/home/cltbld', 07:27:05 INFO - 'LANG': 'en_US.UTF-8', 07:27:05 INFO - 'LANGUAGE': 'en_US:en', 07:27:05 INFO - 'LOGNAME': 'cltbld', 07:27:05 INFO - 'MAIL': '/var/mail/cltbld', 07:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:05 INFO - 'MOZ_NO_REMOTE': '1', 07:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:05 INFO - 'NO_EM_RESTART': '1', 07:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:05 INFO - 'PWD': '/builds/slave/test', 07:27:05 INFO - 'SHELL': '/bin/bash', 07:27:05 INFO - 'SHLVL': '1', 07:27:05 INFO - 'TERM': 'linux', 07:27:05 INFO - 'TMOUT': '86400', 07:27:05 INFO - 'USER': 'cltbld', 07:27:05 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:05 INFO - '_': '/tools/buildbot/bin/python'} 07:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:05 INFO - Downloading/unpacking jsonschema 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:27:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 07:27:06 INFO - Downloading/unpacking functools32 (from jsonschema) 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27:07 INFO - Downloading functools32-3.2.3-2.tar.gz 07:27:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 07:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:27:07 INFO - warning: no files found matching '*.txt' 07:27:07 INFO - no previously-included directories found matching 'build' 07:27:07 INFO - no previously-included directories found matching 'dist' 07:27:07 INFO - no previously-included directories found matching '.git*' 07:27:07 INFO - Installing collected packages: jsonschema, functools32 07:27:07 INFO - Running setup.py install for functools32 07:27:07 INFO - warning: no files found matching '*.txt' 07:27:07 INFO - no previously-included directories found matching 'build' 07:27:07 INFO - no previously-included directories found matching 'dist' 07:27:07 INFO - no previously-included directories found matching '.git*' 07:27:07 INFO - Successfully installed jsonschema functools32 07:27:07 INFO - Cleaning up... 07:27:07 INFO - Return code: 0 07:27:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:27:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:27:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:27:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f2b08ed7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28e58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29b3240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, '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': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', '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 07:27:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 07:27:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 07:27:07 INFO - Using env: {'DISPLAY': ':0', 07:27:07 INFO - 'HOME': '/home/cltbld', 07:27:07 INFO - 'LANG': 'en_US.UTF-8', 07:27:07 INFO - 'LANGUAGE': 'en_US:en', 07:27:07 INFO - 'LOGNAME': 'cltbld', 07:27:07 INFO - 'MAIL': '/var/mail/cltbld', 07:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:07 INFO - 'MOZ_NO_REMOTE': '1', 07:27:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:07 INFO - 'NO_EM_RESTART': '1', 07:27:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:07 INFO - 'PWD': '/builds/slave/test', 07:27:07 INFO - 'SHELL': '/bin/bash', 07:27:07 INFO - 'SHLVL': '1', 07:27:07 INFO - 'TERM': 'linux', 07:27:07 INFO - 'TMOUT': '86400', 07:27:07 INFO - 'USER': 'cltbld', 07:27:07 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:07 INFO - '_': '/tools/buildbot/bin/python'} 07:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:27:07 INFO - Downloading/unpacking flake8 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27: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 07:27:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:27:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 07:27:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:27:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 07:27:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 07:27:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:27:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 07:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:27:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:27:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:27:09 INFO - no previously-included directories found matching 'docs/_build' 07:27:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:27:10 INFO - Running setup.py install for pep8 07:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:27:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:27:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:27:10 INFO - no previously-included directories found matching 'docs/_build' 07:27:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:27:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:27:10 INFO - Cleaning up... 07:27:10 INFO - Return code: 0 07:27:10 INFO - Running post-action listener: _resource_record_post_action 07:27:10 INFO - Running post-action listener: _start_resource_monitoring 07:27:10 INFO - Starting resource monitoring. 07:27:10 INFO - ##### 07:27:10 INFO - ##### Running install step. 07:27:10 INFO - ##### 07:27:10 INFO - Running pre-action listener: _resource_record_pre_action 07:27:10 INFO - Running main action method: install 07:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:27:10 INFO - Reading from file tmpfile_stdout 07:27:10 INFO - Detecting whether we're running mozinstall >=1.0... 07:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:27:10 INFO - Reading from file tmpfile_stdout 07:27:10 INFO - Output received: 07:27:10 INFO - Usage: mozinstall [options] installer 07:27:10 INFO - Options: 07:27:10 INFO - -h, --help show this help message and exit 07:27:10 INFO - -d DEST, --destination=DEST 07:27:10 INFO - Directory to install application into. [default: 07:27:10 INFO - "/builds/slave/test"] 07:27:10 INFO - --app=APP Application being installed. [default: firefox] 07:27:10 INFO - mkdir: /builds/slave/test/build/application 07:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:27:24 INFO - Reading from file tmpfile_stdout 07:27:24 INFO - Output received: 07:27:24 INFO - /builds/slave/test/build/application/firefox/firefox 07:27:24 INFO - Running post-action listener: _resource_record_post_action 07:27:24 INFO - ##### 07:27:24 INFO - ##### Running run-tests step. 07:27:24 INFO - ##### 07:27:24 INFO - Running pre-action listener: _resource_record_pre_action 07:27:24 INFO - Running main action method: run_tests 07:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:27:24 INFO - Python 2.7.3 07:27:24 INFO - Return code: 0 07:27:24 INFO - grabbing minidump binary from tooltool 07:27: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')]} 07:27: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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a420b0>, '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 0x2a42fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a43440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:27: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 07:27: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 07:27:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:27:24 INFO - Return code: 0 07:27:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:27:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:27:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:27:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:27:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:27:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:27:25 INFO - Return code: 0 07:27:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] in /builds/slave/test/build 07:27:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost 07:27:25 INFO - Using env: {'DISPLAY': ':0', 07:27:25 INFO - 'HOME': '/home/cltbld', 07:27:25 INFO - 'LANG': 'en_US.UTF-8', 07:27:25 INFO - 'LANGUAGE': 'en_US:en', 07:27:25 INFO - 'LOGNAME': 'cltbld', 07:27:25 INFO - 'MAIL': '/var/mail/cltbld', 07:27:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:27:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:27:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:27:25 INFO - 'MOZ_NO_REMOTE': '1', 07:27:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:27:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:27:25 INFO - 'NO_EM_RESTART': '1', 07:27:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:27:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:27:25 INFO - 'PWD': '/builds/slave/test', 07:27:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:27:25 INFO - 'SHELL': '/bin/bash', 07:27:25 INFO - 'SHLVL': '1', 07:27:25 INFO - 'TERM': 'linux', 07:27:25 INFO - 'TMOUT': '86400', 07:27:25 INFO - 'USER': 'cltbld', 07:27:25 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1448805477.55411-1061541873', 07:27:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:27:25 INFO - '_': '/tools/buildbot/bin/python'} 07:27:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] with output_timeout 3600 07:27:26 INFO - mozversion INFO | application_buildid: 20151129064331 07:27:26 INFO - mozversion INFO | application_changeset: c952179edbfc58e2e8db3df6782c943782229112 07:27:26 INFO - mozversion INFO | application_display_name: Nightly 07:27:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:27:26 INFO - mozversion INFO | application_name: Firefox 07:27:26 INFO - mozversion INFO | application_remotingname: firefox 07:27:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 07:27:26 INFO - mozversion INFO | application_vendor: Mozilla 07:27:26 INFO - mozversion INFO | application_version: 45.0a1 07:27:26 INFO - mozversion INFO | platform_buildid: 20151129064331 07:27:26 INFO - mozversion INFO | platform_changeset: c952179edbfc58e2e8db3df6782c943782229112 07:27:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 07:27:26 INFO - mozversion INFO | platform_version: 45.0a1 07:27:26 INFO - 2015-11-29 07:27:26,011 DEBUG : using testdate: 1448810846 07:27:26 INFO - 2015-11-29 07:27:26,011 DEBUG : actual date: 1448810846 07:27:26 INFO - 2015-11-29 07:27:26,019 INFO : starting webserver on 'localhost:53234' 07:27:26 INFO - 2015-11-29 07:27:26,020 INFO : Starting test suite talos-linux64-ix-003.e 07:27:26 INFO - 2015-11-29 07:27:26,020 INFO : Starting test dromaeo_css 07:27:26 INFO - 2015-11-29 07:27:26,020 DEBUG : operating with platform_type : linux_ 07:27:26 INFO - 2015-11-29 07:27:26,021 INFO : Initialising browser for dromaeo_css test... 07:27:26 INFO - 2015-11-29 07:27:26,037 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp95Lcgp/profile http://localhost:53234/getInfo.html 07:27:30 INFO - 2015-11-29 07:27:30,839 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:27:30 INFO - 2015-11-29 07:27:30,839 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:27:30 INFO - 2015-11-29 07:27:30,839 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:27:30 INFO - 2015-11-29 07:27:30,839 DEBUG : BROWSER_OUTPUT: __metrics 07:27:31 INFO - 2015-11-29 07:27:31,930 INFO : Browser initialized. 07:27:31 INFO - 2015-11-29 07:27:31,930 INFO : Running cycle 1/1 for dromaeo_css test... 07:27:31 INFO - 2015-11-29 07:27:31,931 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp95Lcgp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:27:43 INFO - 2015-11-29 07:27:43,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 138694656 07:27:43 INFO - 2015-11-29 07:27:43,904 DEBUG : BROWSER_OUTPUT: 07:28:33 INFO - 2015-11-29 07:28:33,553 DEBUG : BROWSER_OUTPUT: 1448810913551 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] 07:30:44 INFO - 2015-11-29 07:30:44,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53234/tests/dromaeo/cssquery-ext.html) 07:30:45 INFO - 2015-11-29 07:30:45,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 142880768 07:30:45 INFO - 2015-11-29 07:30:45,978 DEBUG : BROWSER_OUTPUT: 07:33:47 INFO - 2015-11-29 07:33:47,921 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-ext.html (next: http://localhost:53234/tests/dromaeo/cssquery-jquery.html) 07:33:48 INFO - 2015-11-29 07:33:48,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 142020608 07:33:48 INFO - 2015-11-29 07:33:48,515 DEBUG : BROWSER_OUTPUT: 07:36:50 INFO - 2015-11-29 07:36:50,283 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53234/tests/dromaeo/cssquery-mootools.html) 07:36:50 INFO - 2015-11-29 07:36:50,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 144076800 07:36:50 INFO - 2015-11-29 07:36:50,870 DEBUG : BROWSER_OUTPUT: 07:39:52 INFO - 2015-11-29 07:39:52,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53234/tests/dromaeo/cssquery-prototype.html) 07:39:53 INFO - 2015-11-29 07:39:53,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 142151680 07:39:53 INFO - 2015-11-29 07:39:53,210 DEBUG : BROWSER_OUTPUT: 07:42:55 INFO - 2015-11-29 07:42:55,714 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53234/tests/dromaeo/cssquery-yui.html) 07:42:56 INFO - 2015-11-29 07:42:56,327 DEBUG : BROWSER_OUTPUT: RSS: Main: 141983744 07:42:56 INFO - 2015-11-29 07:42:56,328 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,674 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/dromaeo/cssquery-yui.html (next: http://localhost:53234/tests/dromaeo/cssquery-dojo.html) 07:45:58 INFO - 2015-11-29 07:45:58,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 145522688 07:45:58 INFO - 2015-11-29 07:45:58,924 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,926 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:45:58 INFO - 2015-11-29 07:45:58,926 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:45:58 INFO - 2015-11-29 07:45:58,926 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:45:58 INFO - 2015-11-29 07:45:58,927 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:45:58 INFO - 2015-11-29 07:45:58,928 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2655.389260470092;2718.10302600142;2749.7937654675893;2750.4499100179955;2761.0060378263815;4526.072155208182;4640.605548528375;4648.349231107648;4654;4681.719096854187;4511.323301504768;4553.954460455395;4592.9665825189395;4597.103564804865;4636.281376386657;4098.159877225162;4234.259004674177;4330.97834510827;4364.825406983717;4403;4134.855280065199;4330.177266319405;4365.083332500163;4383.167198232326;4401.031773009932;4238.258304796648;4251.404803327537;4340.08858139791;4366.497852746943;4371.562843715635;4317.373980772802;4349.913001739979;4354.54277300882;4356.847510337123;4379.671524635665;4148.274052040911;4256.489221293465;4314.80583373749;4318.373835793824;4367.781610919441;4503.684742068057;4508.2561377372695;4566.657500687428;4609.631229501631;4620.722756634579;4972.850814475535;4974.0797952378925;4999.600031997432;5006.349174607278;5006.724630145341;4801.087793319258;4928.556429921325;4964.15609346412;5015.072211640858;5038.017586570621;4522.16339977102;4558.430196225472;4571.702839315434;4581.908361832778;4624.699394539344;5077.390713114376;5118.10433174193;5131.974340128276;5136.589072874236;5192.844214673566;4701;4762.3570817939135;4933.161362568372;4945.653804233741;4991.775370108354;4875.049365373789;4945.2087665973995;4950.628702847301;4951;4990.226514890198;4925.778339974638;4927.433345165352;4936.975315123474;4942.258661200777;4988.825391111293;4709.175894218498;4771.665983381199;4812.494688057704;4926.581240594518;4969.776360063734;4121.1345617420675;4130.896727581834;4135.400366946776;4147.896302592399;4150.335946248586;3662.487251784752;3663.68858647018;3665.4135338345736;3685.262947410529;3692.095436618046;4110.979445102756;4192.622663960258;4230.598093181143;4288.935665965013;4289.420928174656;4961.05739909416;4979.228219625964;4993.525615066563;4994.850154495371;5032.84901452957;4273.401723758676;4287.378330142112;4289.442372491619;4290.099079193405;4321.027768752068;4271.231178387858;4344.45694288214;4378.584034516716;4403.911921761547;4456.955430445719;4687.554682305175;4687.99208170245;4689.999999999932;4696.882577935579;4712.340272361872;4787.569118779326;4943.110240156806;4986.80052797884;4990.80036798524;5003.649744517848;4656.068786242833;4673.719576825401;4779.976100119477;4781.784819683192;4821.035792841376;4892.094962431962;4919.950800491949;4930.285108659153;4934.407871055496;4969.602431805519;4755.548222918817;4895.657303988686;4989.625778066587;5004.274380214775;5044.672096313728;4750.714957102585;4754.168020596313;4772.785224664829;4809.927851082167;4843.782029808737;4846.103470857903;4848.927266091082;4850.345203397496;4858.514148585114;4871.634627402888;4584.289435137559;4674.5325467452985;4693.788779504999;4767.022760334193;4810.759462026955;4834.6374021947795;4901.705897646153;4910.92633610489;4930.433000204879;4960.280759289811;4779.426468823764;4789.377380940456;4806.567408933212;4819.0121025189055;4862.246351815475 07:45:58 INFO - 2015-11-29 07:45:58,931 DEBUG : BROWSER_OUTPUT: |1;ext.html;549.6156983983857;578.4418036594683;581.9068948968164;586.2203269651365;589.8525368657836;35737.46393804092;36576.81711591438;36589.63410365899;36601;36611;38504.422433663516;38929.805350973205;39013.60986390135;39135.21729565407;39146.21707565847;7953.687641539153;8162.14297498763;8174.386920980921;8175.14161013094;8182.67269309227;7976.2821346078845;8057.35541156706;8059.314958228558;8071.878921816177;8115.1073381927945;7955.920440795576;8039.598020099001;8057.395695322845;8065.637046332899;8089.433739638228;8049.919500805005;8127.837443251102;8129;8143.429959902809;8154.143814899409;7956.164602716747;8006.079300880415;8018.759437216893;8057.879131813028;8106.148854370265;35604.28791424183;36044.81977590122;36107;36113.097172570895;36190.63809361899;2815.7465828075365;2816.746492815636;2822.252103192656;2949.2626843289177;3361.428557145291;26023;26023.479530409284;26213.21360359192;26222;26393.736062639513;3440.916111424893;3450.0857272822723;3474.1488335358;3507.57909050913;3544.9822750886083;213423;215214.99999999843;215565;216046;216246;48947.510524895006;49849;51729.22406163911;53914;54060.45939540633;55381.446185538436;55535;55611.72194139003;55635.72182139063;55681.164782527485;11817.409129543488;11942.641720748392;11968.700782480508;11985.940070299594;12032.338221397908;8969.686060987835;8993.640254389882;9012.41419307743;9163.862542061874;9175.954120229357;19092.140853661618;19150.233990640496;19268;19290.4212873614;19339.4198174055;7575.886361704579;7750.922490775133;7804.336631386395;7885.723999659985;7969.840603187904;811.1077814404226;838.6351936907465;866.9306455483546;936.7611259128879;956.5982287439292;24646.630300545505;24916.87541562317;25017.249482515555;25221.243362699148;25299.494010119695;936.1714882329188;1021.412687704558;1036.3315661398356;1040.095117247999;1122.2480937771713;13513.594592162252;14192.645183870485;14296.928515357356;14438.494652687315;14493.855061449462;316588;319473;320441;320980;321079;46451;46742;46786;46891.29663055057;46921;1206.6259459567386;1211.9818202726967;1216.3614102596032;1225.185251807538;1225.3995542184443;25736.742632574184;25906.22281331563;26123.216303510926;26189.476210475303;26256;39812.40281395839;40023.99940001523;40394.79802600968;40451.393229101;40469.39295910505;249183;249541;250625;250968;251741;28581.714182857904;29621.407571848875;29639;29674.406511870075;29755.702442975293;37917.241655167294;38017.4297385545;38763.612363876004;39213.2157356857;39411.40882886702;32534;32705.672943271216;33322;33858.49212261867;33869.83065084659;33323.1669208262;33335.49996750002;33566.83216583901;33636.66363336335;33943.151421214665;33343.83328083344;33503.32993340071;33857.322853543286;34038.48942265918;34157.63369465194;28357.716422836333;29284.414311714074;29873.551896722;30317.545236821024;30436.239094022825;23255.76744232536;23677.52644947057;23835.165769197996;24046.51906961886;24070 07:45:58 INFO - 2015-11-29 07:45:58,931 DEBUG : BROWSER_OUTPUT: |2;jquery.html;306.6381669629836;316.1495576898145;321.31720094798555;322.1527382982754;329.170490364282;13592.932035339822;14945.925270373646;15368.231588420576;15440.691186176298;16027.759583606256;8456.746297611082;8667.046624871258;9715.659951901685;14466.927665361658;14548.490802821903;4296.548862369461;4388.97805510972;4403.383526306319;4405.05291362357;4412.602865742083;6371.331010243927;6600;6613.3386661333725;6618.271990081087;6631.336866313378;5938.7624495020145;6085.452309292163;6136.785212517563;6463.709133088999;6588.835279118012;6258.999999999977;6282.623042617434;6315.842103947415;6350.142730731384;6568.934310656928;6282.183316168915;6325.272593651697;6326.272478664966;6329.272133704637;6329.556931014831;19736.70394944077;20440.080196391198;20831.791682083138;21292.680609790867;21351.35945921609;1806.1872157529165;1820.6358728254402;1851.1484717030182;1909.9427017189369;1964.8624596278266;12753.61739147827;12901.612951611467;13208.141470804367;13338.466461341535;13620.931895340558;1850.5743678953781;1868.5889104397147;1922.5482011727233;1961.8136277053657;1966.6165097805933;60761;63371.68314158446;63481.682591586745;63793;64672.35327646758;15468.071915685096;15592.298346574431;15632;15654.921725391529;15671.373145074298;22982.655260171112;23095.88452057729;23222.187223447097;23316.65025024626;23331.416714582272;8025.23760242776;8048.275655191061;8053.033635963722;8077;8140.959295203486;6682.766103186365;6686.1642294713165;6690.19717633887;6690.29751876046;6699.162604674416;7610.6194690265265;7613.5431874087735;7630.7329243476215;7637.045369328834;7638.312551870358;2877.49643812332;2891.407261511385;2955.6453225612636;2978.9702102979127;3073.4775088235056;838.2455789789238;896.2292428511475;915.8900931888217;938.8874184091873;993.096282383028;15628.531144065697;15694.607634808992;15790.763138552931;16008.11955342444;16110;791.6595863778631;815.995920020396;848.6181218451721;876.9429987050821;909.0954500272271;7258.709651613982;8732.912670873337;9044.954775226082;9173.312001599907;9230.769230769285;339722;345531;507864;510877;515321;74360.25639743534;75386.24613753903;76122.6193869027;76712.61643691857;76905.61547192228;1018.5467466977273;1028.2493779540978;1029.8867124616286;1043.8642976413018;1055.72551136705;14572.635684107981;14643.26783660834;14660;14680;14792.11247325164;32466.188345291557;33122.66877331196;33359.166020849676;33445.498317525744;34245.657543425245;43028;43629.345559817266;44486.555134448245;44684.776576116914;44728.10543789041;7958.3235424989525;8699.652013919625;8700.12998700125;8705.956470217609;8723.738287851374;13861.79207311871;15211.239438028275;15703.13632750164;15980.600484987503;16206.189690515192;13582.59252222435;13611.183328999896;13748.243846588533;13755.862441375459;14043.719125617226;13105.14816536922;13236;13249;13261.469541218243;13625.386857591235;13327;13443.126196797177;13530.729385412433;13624.455021799016;13808.930955345159;7516.774496765106;7740.26467485588;7748.496347737379;7954.482958607672;8072.87890681652;8482.363822713198;8607.82784344297;13406.597802065953;13515.932420337835;13821.516246931711 07:45:58 INFO - 2015-11-29 07:45:58,932 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1344.3412727763398;1349.7705390083686;1394.177435313165;1566.2560283865162;1616.345380121051;5595.4124816894255;5928.199693041438;6480.31956644552;6745.494087943405;6754.560953538023;6281.371862813717;6495.155629768124;6525.249596296428;6536.248331441886;6573.962071757758;1938.835199008086;1982.672858978274;1983.9404817855489;1985.6922177062506;1985.7319261899675;1922.8365588924958;1945.9221631134737;1964;1986.4437957371886;1994.6908229224493;1961.9411417657493;1966.2724791827045;1986.9105890234973;2002.6895831146196;2003.3188715836613;1951.0829909942304;1954.9609007819836;1960.5000724815222;1965.518447980254;1967.4491142480128;1926.5376309685714;1954.2769175405192;1963.3619073801071;1969.094216660338;1969.3993332033724;6567.376099270562;6593.044008618724;6629.834254143685;6637.303083176245;6642.7342906284175;3784.2242340320167;3856.3636999895266;3859.671927886133;3859.9228015439817;3862.092408284051;2149.7420309562795;2188.070070220166;2465.0879174705296;2517.9118730844516;2522.495500899809;804.3766081286991;828.337330135895;842.4650347029619;842.9241368276885;881.4226681523612;8714.25928796053;8726.825463490759;8734.2139207471;8749.212570868589;8784.341174411944;3453.8273086346085;3456.8962931112105;3527.6295988921306;3540.9645903540636;3560.537130173113;7515.661795219119;7540.698372065166;7573.962130189424;7579.204183560757;7586.051743532059;3759.0790256386813;3760.8119594020736;3772.358699021173;3778.6410291022307;3797.240551889633;3408.2672225471642;3444.156181735491;3444.4488881778793;3445.431503801895;3448.3965306071336;3175.364927014606;3185.8566364513654;3189.1389324882616;3210.7110360067704;3217.3082787200865;962.5860879821604;969.8739163908788;972.2805124208177;972.9756756081155;979.3340528440586;1169.1173164260929;1180.1797750563276;1181.4388165621433;1194.5341316886422;1206.330486579939;6018.127371531191;6119.877602447926;6194.5973511721595;6196.039613859769;6198.876022479525;941.5527624378366;959.6449313753816;962.4706411473801;1040.54216144896;1062.9574817007388;5075.467075956972;5083.694978301313;5094.49055094495;5102.515261050195;5115.258287548285;7018.2630823762765;7045.401140903081;7621.275978782007;7636.121845987783;7688;7456.956026156343;7462.85074298511;7467.589282589401;7487.925120748831;7500.512466689648;6218.129461875341;6234.781782637586;6306.558540902093;6325.525585581007;6358.650274234869;6248.68756562161;6260.280067792262;6312.43188113072;6334.778282760081;6334.841628959313;6404.551681382445;6472.611643301417;6582.012698095133;7031.43748500129;7034.050403195503;7020.473464490082;7050.15398152225;7187.6765545551625;7210.206877243604;7287.635618218962;5927.347991720821;5946.167536544974;5999.370066142992;6001.639901605918;6018.127371531016;5934.050551911673;6141.7543298267565;6337.556371053982;6352.428281454691;6379.266384365672;5944.5838791284195;6024.668643224665;6027.668478233739;6048.848778780566;6059.424354686298;5903.763849446146;5940.524758019435;5945.91081133792;6019.849503762441;6089.451949324582;5953.434423729739;5971.373005834324;5980.671063091571;5989.910151347646;6017.638941663514;5502.312210973629;5584.636998595241;5598.832035038955;5619.494245517923;5625.212470254086;5641.492265696107;5666.886662266649;5674.716264186856;5681.289838770153;5713.771449141987 07:45:58 INFO - 2015-11-29 07:45:58,933 DEBUG : BROWSER_OUTPUT: |4;prototype.html;285.9142257322803;287.1915557705061;287.9524878395065;291.5961393470044;300.8495752123938;5450.56395488361;5501.312335958005;5563.610547261694;5567.972160139194;5633.8309850704445;5471.343438787351;5544.417836127209;5547.833564993057;5554.222408862763;5567.4432556744305;4239.830406783725;4241.639460645849;4241.787910604465;4249.25638013348;4250.744955302676;4137.834486620532;4192.7840926131385;4195.895102622444;4207.263728847454;4265.8080386382535;4186.309258972281;4205.327147656376;4205.537390887;4212.031232816454;4220.219259436999;4202.3486359614;4207.51613564441;4213.7893105344765;4217.472815898012;4222.662187025031;3691.1141326081815;3691.169486865461;3691.280200360931;3697.149655579232;3698.0754811297174;5188.481151884819;5220.947790522122;5328.893422131575;5347.41178470368;5380.977614253279;4878.07316609843;4888.095702295087;4893.21708526634;4894.4371397289415;4896.4858689837765;5814.418558144152;5827.4172582741385;5828.475437210629;5836.649801011953;5837.678927658977;4045.0494133878515;4051.3315302974975;4054.3310353791885;4057.046594050396;4065.735727177724;5854.707264636793;6073.696315184267;6084;6089.360617135181;6096.4817990470865;4178.247915375201;4185.727927684691;4188.350805625133;4191.874243772691;4194.035371864488;4249.936250956238;4252.149570085995;4253.957460425418;4256.7445953242905;4260.020195355086;4854.757262136879;4858.1012512685265;4858.73276969763;4859.902801943941;4869.659123861366;5595.776168953278;5746.626469279484;5776.306843178845;5798.971005144947;5803.593748437654;1457.7084583083426;1487.9776803347959;1488.3153749275239;1491.6569189086572;1493.089215578518;1013.5793645636978;1027.732789474727;1034.0848349210853;1043.1498328861953;1053.3258714422775;4962.503749625081;4978.8257410990445;4989.725565093882;5014.648974571818;5026.2460630904925;5997.910031349533;5999.5800293979;6011.549133814981;6023.759049638053;6028.125921741323;4986.975065124651;4992.925106123411;5015.297858299841;5018.774155163026;5023.773930173151;3162.2410621450686;3201.983990080035;3224.500202468621;3234.385466761355;3236.9838150809564;5582;5608.69152196625;5610.046292130348;5615.887682246333;5634.7182640867795;6109.358517355614;6238.313785483593;6239.06414037898;6245.344238854855;6260.718267677966;5628.521575666033;5640.5487560995025;5690.772369105272;5699.857503562361;5702.8289151325525;6059.606125601822;6071.27144742616;6072.514198864169;6078.179445774764;6092.817215483543;5765.336986246468;5929.0513517836935;5941.702914854326;5943.791967281298;5945.048792193228;5685.459881311269;5689.857753556194;5691.715414229355;5698.0598201296325;5702.144678298288;3968.980155099206;4033.9193216134927;4042.191561687733;4044.8786536403954;4052.7639070083906;5901.232839730807;5995.280566332068;6039.637621742534;6043.12374705674;6053.545984051126;5822.854428639318;5836.241288632451;5840.357560668238;5845.678487683048;5845.736941837543;5843.766249349978;5847;5852.063669812809;5859.091840764689;5859.707014649336;5579.644447948393;5729.799457018984;5752.654840709571;5758.769649213985;5760.107183386582;5821.359650438364;5873.823785286449;5874.500667443315;5877.500412464989;5889.646621202742;5697.601167918206;5728.971355143198;5731.971340143273;5738.68437235956;5761.107028410603 07:45:58 INFO - 2015-11-29 07:45:58,934 DEBUG : BROWSER_OUTPUT: |5;yui.html;784.7175016993884;786.2530595933861;788.1015642167926;788.9447738658295;797.1629788721842;9932;10009;10049.799004019915;10058.647947321837;10077.496125193748;10096.596136154563;10259.589616415353;10281.485925703704;10300.07299343061;10300.793984120313;598.9071693887454;607.2045620237503;752.6613024139145;752.947293689442;766.9309762121408;767.1446337333888;769.2845653542204;774.2644487736644;774.2799196747023;774.3843644302765;803.120582961656;810.8864758933754;811.2374368094003;811.3265989228955;811.6104269950428;797.948133371329;799.6521513141802;801.218811658634;802.1978021978022;805.4563169860351;593.715016791938;595.553334998751;597.4145337569206;597.6025942748067;598.2282855116938;10225.642102526375;10277.075063244269;10289.176865850714;10292.433916134609;10305.742356441075;11167;11280.2667826591;11377.772444551145;11596.942015289955;11679.532818687243;9204.907950920538;9387.061293870558;9474.147326740627;9485.857712134324;9527.333086683935;3084.5373194020854;3091.381723655278;3134.3417882244757;3163.114327988167;3170.5719727836677;40584;40629.796851015555;40632.79683601563;40695;40774.79612601918;9108.635654573874;9243.445393276425;9272.350935434455;9377.015413381632;9405.106514881076;16109;16119.919400402923;16125;16163.838361616468;16203.189840507928;6343.555951083472;6370;6473.385028422293;6611.834704132435;6646.468282537386;3524.5770507538746;3623.4745961835915;3629.909252268714;3636.3636363636256;3656.8902932912056;2593.6757905261843;2631.2763989902546;2632.2498088044817;2639.8152129351147;2649.152271273174;468.76327454635873;473.3515084334415;473.4082397003745;488.6213184781823;490.3380436410888;547.8329109621603;576.7981206577748;612.6385432594791;623.4918541388754;637.4486069549806;7541.886871696929;7691.153973062958;7871.291583757489;8230.670773169126;8321.791955201168;483.13036534238233;489.7355428068874;526.6524094097877;596.9820905372845;650.2944305428568;3474.2183008822813;3543.53933988585;3645.143391303042;3688.207035487383;3768.2275133597545;46338.5366146341;46825.5317446828;49654.503454965714;50293.497065029616;50319.74840125776;44031.779841100586;44755;44835.77582112069;45439.772801136445;45559.77220113944;41374.37938430926;41378.58621413808;41713;41734.791326043785;42055.7897210512;7839.843203135792;7853.504609039047;8017.919820801717;8233.79415514617;8338.24955753985;31049.68950310468;31742;31915.680843191272;33201.66798331986;34089.65910340964;38637.42043869288;39084.2183156341;39439.80280098696;39574.40638390484;40145.1970960585;9281.350305478822;9613.134817866425;9805.313628046253;10092.798144036931;10161.745956351151;11494.770104598028;11640.243384180001;11744.765104697688;11835.230710004167;12421;9869.703908882744;9939.055789699967;10849.294795838245;10918.945405272923;11119.722006949567;9795.510224488604;9969;10696;10831.350118992885;11446.828297575377;9186.40288381253;9955.751106222113;10136.290459667753;11041.282316649393;11442.713932151762;9541.618335266512;9725.173360264456;9748.85376719364;9957.203423726229;10008.09927106564;9526.333156678966;9601.903980960102;9627.374220675914;9645.42127472354;9779.462129582656 07:45:58 INFO - 2015-11-29 07:45:58,934 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:45:58 INFO - 2015-11-29 07:45:58,934 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:45:58 INFO - 2015-11-29 07:45:58,934 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,315 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: __startTimestamp1448811958924__endTimestamp 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: Number of tests: 6 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4584.58 Median:4693.79 stddev:465.13 (9.9%) stddev-sans-first:441.25 07:45:58 INFO - 2015-11-29 07:45:58,935 DEBUG : BROWSER_OUTPUT: Values: 2655.4 2718.1 2749.8 2750.4 2761.0 4526.1 4640.6 4648.3 4654.0 4681.7 4511.3 4554.0 4593.0 4597.1 4636.3 4098.2 4234.3 4331.0 4364.8 4403.0 4134.9 4330.2 4365.1 4383.2 4401.0 4238.3 4251.4 4340.1 4366.5 4371.6 4317.4 4349.9 4354.5 4356.8 4379.7 4148.3 4256.5 4314.8 4318.4 4367.8 4503.7 4508.3 4566.7 4609.6 4620.7 4972.9 4974.1 4999.6 5006.3 5006.7 4801.1 4928.6 4964.2 5015.1 5038.0 4522.2 4558.4 4571.7 4581.9 4624.7 5077.4 5118.1 5132.0 5136.6 5192.8 4701.0 4762.4 4933.2 4945.7 4991.8 4875.0 4945.2 4950.6 4951.0 4990.2 4925.8 4927.4 4937.0 4942.3 4988.8 4709.2 4771.7 4812.5 4926.6 4969.8 4121.1 4130.9 4135.4 4147.9 4150.3 3662.5 3663.7 3665.4 3685.3 3692.1 4111.0 4192.6 4230.6 4288.9 4289.4 4961.1 4979.2 4993.5 4994.9 5032.8 4273.4 4287.4 4289.4 4290.1 4321.0 4271.2 4344.5 4378.6 4403.9 4457.0 4687.6 4688.0 4690.0 4696.9 4712.3 4787.6 4943.1 4986.8 4990.8 5003.6 4656.1 4673.7 4780.0 4781.8 4821.0 4892.1 4920.0 4930.3 4934.4 4969.6 4755.5 4895.7 4989.6 5004.3 5044.7 4750.7 4754.2 4772.8 4809.9 4843.8 4846.1 4848.9 4850.3 4858.5 4871.6 4584.3 4674.5 4693.8 4767.0 4810.8 4834.6 4901.7 4910.9 4930.4 4960.3 4779.4 4789.4 4806.6 4819.0 4862.2 07:45:58 INFO - 2015-11-29 07:45:58,936 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,936 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41755.41 Median:3462.12 stddev:69435.81 (2005.6%) stddev-sans-first:69561.66 07:45:58 INFO - 2015-11-29 07:45:58,936 DEBUG : BROWSER_OUTPUT: Values: 549.6 578.4 581.9 586.2 589.9 35737.5 36576.8 36589.6 36601.0 36611.0 38504.4 38929.8 39013.6 39135.2 39146.2 7953.7 8162.1 8174.4 8175.1 8182.7 7976.3 8057.4 8059.3 8071.9 8115.1 7955.9 8039.6 8057.4 8065.6 8089.4 8049.9 8127.8 8129.0 8143.4 8154.1 7956.2 8006.1 8018.8 8057.9 8106.1 35604.3 36044.8 36107.0 36113.1 36190.6 2815.7 2816.7 2822.3 2949.3 3361.4 26023.0 26023.5 26213.2 26222.0 26393.7 3440.9 3450.1 3474.1 3507.6 3545.0 213423.0 215215.0 215565.0 216046.0 216246.0 48947.5 49849.0 51729.2 53914.0 54060.5 55381.4 55535.0 55611.7 55635.7 55681.2 11817.4 11942.6 11968.7 11985.9 12032.3 8969.7 8993.6 9012.4 9163.9 9176.0 19092.1 19150.2 19268.0 19290.4 19339.4 7575.9 7750.9 7804.3 7885.7 7969.8 811.1 838.6 866.9 936.8 956.6 24646.6 24916.9 25017.2 25221.2 25299.5 936.2 1021.4 1036.3 1040.1 1122.2 13513.6 14192.6 14296.9 14438.5 14493.9 316588.0 319473.0 320441.0 320980.0 321079.0 46451.0 46742.0 46786.0 46891.3 46921.0 1206.6 1212.0 1216.4 1225.2 1225.4 25736.7 25906.2 26123.2 26189.5 26256.0 39812.4 40024.0 40394.8 40451.4 40469.4 249183.0 249541.0 250625.0 250968.0 251741.0 28581.7 29621.4 29639.0 29674.4 29755.7 37917.2 38017.4 38763.6 39213.2 39411.4 32534.0 32705.7 33322.0 33858.5 33869.8 33323.2 33335.5 33566.8 33636.7 33943.2 33343.8 33503.3 33857.3 34038.5 34157.6 28357.7 29284.4 29873.6 30317.5 30436.2 23255.8 23677.5 23835.2 24046.5 24070.0 07:45:58 INFO - 2015-11-29 07:45:58,936 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,937 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26238.25 Median:189888.33 stddev:73917.80 (38.9%) stddev-sans-first:74099.51 07:45:58 INFO - 2015-11-29 07:45:58,938 DEBUG : BROWSER_OUTPUT: Values: 306.6 316.1 321.3 322.2 329.2 13592.9 14945.9 15368.2 15440.7 16027.8 8456.7 8667.0 9715.7 14466.9 14548.5 4296.5 4389.0 4403.4 4405.1 4412.6 6371.3 6600.0 6613.3 6618.3 6631.3 5938.8 6085.5 6136.8 6463.7 6588.8 6259.0 6282.6 6315.8 6350.1 6568.9 6282.2 6325.3 6326.3 6329.3 6329.6 19736.7 20440.1 20831.8 21292.7 21351.4 1806.2 1820.6 1851.1 1909.9 1964.9 12753.6 12901.6 13208.1 13338.5 13620.9 1850.6 1868.6 1922.5 1961.8 1966.6 60761.0 63371.7 63481.7 63793.0 64672.4 15468.1 15592.3 15632.0 15654.9 15671.4 22982.7 23095.9 23222.2 23316.7 23331.4 8025.2 8048.3 8053.0 8077.0 8141.0 6682.8 6686.2 6690.2 6690.3 6699.2 7610.6 7613.5 7630.7 7637.0 7638.3 2877.5 2891.4 2955.6 2979.0 3073.5 838.2 896.2 915.9 938.9 993.1 15628.5 15694.6 15790.8 16008.1 16110.0 791.7 816.0 848.6 876.9 909.1 7258.7 8732.9 9045.0 9173.3 9230.8 339722.0 345531.0 507864.0 510877.0 515321.0 74360.3 75386.2 76122.6 76712.6 76905.6 1018.5 1028.2 1029.9 1043.9 1055.7 14572.6 14643.3 14660.0 14680.0 14792.1 32466.2 33122.7 33359.2 33445.5 34245.7 43028.0 43629.3 44486.6 44684.8 44728.1 7958.3 8699.7 8700.1 8706.0 8723.7 13861.8 15211.2 15703.1 15980.6 16206.2 13582.6 13611.2 13748.2 13755.9 14043.7 13105.1 13236.0 13249.0 13261.5 13625.4 13327.0 13443.1 13530.7 13624.5 13808.9 7516.8 7740.3 7748.5 7954.5 8072.9 8482.4 8607.8 13406.6 13515.9 13821.5 07:45:58 INFO - 2015-11-29 07:45:58,938 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,938 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4586.76 Median:5949.80 stddev:2316.41 (38.9%) stddev-sans-first:2310.09 07:45:58 INFO - 2015-11-29 07:45:58,940 DEBUG : BROWSER_OUTPUT: Values: 1344.3 1349.8 1394.2 1566.3 1616.3 5595.4 5928.2 6480.3 6745.5 6754.6 6281.4 6495.2 6525.2 6536.2 6574.0 1938.8 1982.7 1983.9 1985.7 1985.7 1922.8 1945.9 1964.0 1986.4 1994.7 1961.9 1966.3 1986.9 2002.7 2003.3 1951.1 1955.0 1960.5 1965.5 1967.4 1926.5 1954.3 1963.4 1969.1 1969.4 6567.4 6593.0 6629.8 6637.3 6642.7 3784.2 3856.4 3859.7 3859.9 3862.1 2149.7 2188.1 2465.1 2517.9 2522.5 804.4 828.3 842.5 842.9 881.4 8714.3 8726.8 8734.2 8749.2 8784.3 3453.8 3456.9 3527.6 3541.0 3560.5 7515.7 7540.7 7574.0 7579.2 7586.1 3759.1 3760.8 3772.4 3778.6 3797.2 3408.3 3444.2 3444.4 3445.4 3448.4 3175.4 3185.9 3189.1 3210.7 3217.3 962.6 969.9 972.3 973.0 979.3 1169.1 1180.2 1181.4 1194.5 1206.3 6018.1 6119.9 6194.6 6196.0 6198.9 941.6 959.6 962.5 1040.5 1063.0 5075.5 5083.7 5094.5 5102.5 5115.3 7018.3 7045.4 7621.3 7636.1 7688.0 7457.0 7462.9 7467.6 7487.9 7500.5 6218.1 6234.8 6306.6 6325.5 6358.7 6248.7 6260.3 6312.4 6334.8 6334.8 6404.6 6472.6 6582.0 7031.4 7034.1 7020.5 7050.2 7187.7 7210.2 7287.6 5927.3 5946.2 5999.4 6001.6 6018.1 5934.1 6141.8 6337.6 6352.4 6379.3 5944.6 6024.7 6027.7 6048.8 6059.4 5903.8 5940.5 5945.9 6019.8 6089.5 5953.4 5971.4 5980.7 5989.9 6017.6 5502.3 5584.6 5598.8 5619.5 5625.2 5641.5 5666.9 5674.7 5681.3 5713.8 07:45:58 INFO - 2015-11-29 07:45:58,940 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,940 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4806.70 Median:5460.95 stddev:1422.52 (26.0%) stddev-sans-first:1385.45 07:45:58 INFO - 2015-11-29 07:45:58,941 DEBUG : BROWSER_OUTPUT: Values: 285.9 287.2 288.0 291.6 300.8 5450.6 5501.3 5563.6 5568.0 5633.8 5471.3 5544.4 5547.8 5554.2 5567.4 4239.8 4241.6 4241.8 4249.3 4250.7 4137.8 4192.8 4195.9 4207.3 4265.8 4186.3 4205.3 4205.5 4212.0 4220.2 4202.3 4207.5 4213.8 4217.5 4222.7 3691.1 3691.2 3691.3 3697.1 3698.1 5188.5 5220.9 5328.9 5347.4 5381.0 4878.1 4888.1 4893.2 4894.4 4896.5 5814.4 5827.4 5828.5 5836.6 5837.7 4045.0 4051.3 4054.3 4057.0 4065.7 5854.7 6073.7 6084.0 6089.4 6096.5 4178.2 4185.7 4188.4 4191.9 4194.0 4249.9 4252.1 4254.0 4256.7 4260.0 4854.8 4858.1 4858.7 4859.9 4869.7 5595.8 5746.6 5776.3 5799.0 5803.6 1457.7 1488.0 1488.3 1491.7 1493.1 1013.6 1027.7 1034.1 1043.1 1053.3 4962.5 4978.8 4989.7 5014.6 5026.2 5997.9 5999.6 6011.5 6023.8 6028.1 4987.0 4992.9 5015.3 5018.8 5023.8 3162.2 3202.0 3224.5 3234.4 3237.0 5582.0 5608.7 5610.0 5615.9 5634.7 6109.4 6238.3 6239.1 6245.3 6260.7 5628.5 5640.5 5690.8 5699.9 5702.8 6059.6 6071.3 6072.5 6078.2 6092.8 5765.3 5929.1 5941.7 5943.8 5945.0 5685.5 5689.9 5691.7 5698.1 5702.1 3969.0 4033.9 4042.2 4044.9 4052.8 5901.2 5995.3 6039.6 6043.1 6053.5 5822.9 5836.2 5840.4 5845.7 5845.7 5843.8 5847.0 5852.1 5859.1 5859.7 5579.6 5729.8 5752.7 5758.8 5760.1 5821.4 5873.8 5874.5 5877.5 5889.6 5697.6 5729.0 5732.0 5738.7 5761.1 07:45:58 INFO - 2015-11-29 07:45:58,942 DEBUG : BROWSER_OUTPUT: 07:45:58 INFO - 2015-11-29 07:45:58,942 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12182.48 Median:478.27 stddev:13871.97 (2900.5%) stddev-sans-first:13884.48 07:45:58 INFO - 2015-11-29 07:45:58,942 DEBUG : BROWSER_OUTPUT: Values: 784.7 786.3 788.1 788.9 797.2 9932.0 10009.0 10049.8 10058.6 10077.5 10096.6 10259.6 10281.5 10300.1 10300.8 598.9 607.2 752.7 752.9 766.9 767.1 769.3 774.3 774.3 774.4 803.1 810.9 811.2 811.3 811.6 797.9 799.7 801.2 802.2 805.5 593.7 595.6 597.4 597.6 598.2 10225.6 10277.1 10289.2 10292.4 10305.7 11167.0 11280.3 11377.8 11596.9 11679.5 9204.9 9387.1 9474.1 9485.9 9527.3 3084.5 3091.4 3134.3 3163.1 3170.6 40584.0 40629.8 40632.8 40695.0 40774.8 9108.6 9243.4 9272.4 9377.0 9405.1 16109.0 16119.9 16125.0 16163.8 16203.2 6343.6 6370.0 6473.4 6611.8 6646.5 3524.6 3623.5 3629.9 3636.4 3656.9 2593.7 2631.3 2632.2 2639.8 2649.2 468.8 473.4 473.4 488.6 490.3 547.8 576.8 612.6 623.5 637.4 7541.9 7691.2 7871.3 8230.7 8321.8 483.1 489.7 526.7 597.0 650.3 3474.2 3543.5 3645.1 3688.2 3768.2 46338.5 46825.5 49654.5 50293.5 50319.7 44031.8 44755.0 44835.8 45439.8 45559.8 41374.4 41378.6 41713.0 41734.8 42055.8 7839.8 7853.5 8017.9 8233.8 8338.2 31049.7 31742.0 31915.7 33201.7 34089.7 38637.4 39084.2 39439.8 39574.4 40145.2 9281.4 9613.1 9805.3 10092.8 10161.7 11494.8 11640.2 11744.8 11835.2 12421.0 9869.7 9939.1 10849.3 10918.9 11119.7 9795.5 9969.0 10696.0 10831.4 11446.8 9186.4 9955.8 10136.3 11041.3 11442.7 9541.6 9725.2 9748.9 9957.2 10008.1 9526.3 9601.9 9627.4 9645.4 9779.5 07:45:58 INFO - 2015-11-29 07:45:58,942 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:45:58 INFO - 2015-11-29 07:45:58,942 DEBUG : BROWSER_OUTPUT: 07:45:59 INFO - 2015-11-29 07:45:59,654 INFO : Browser exited with error code: 0 07:45:59 INFO - 2015-11-29 07:45:59,670 INFO : Completed test dromaeo_css (00:18:33) 07:45:59 INFO - 2015-11-29 07:45:59,671 INFO : Starting test kraken 07:45:59 INFO - 2015-11-29 07:45:59,671 DEBUG : operating with platform_type : linux_ 07:45:59 INFO - 2015-11-29 07:45:59,671 INFO : Initialising browser for kraken test... 07:45:59 INFO - 2015-11-29 07:45:59,677 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNNcVyQ/profile http://localhost:53234/getInfo.html 07:46:04 INFO - 2015-11-29 07:46:04,419 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:46:04 INFO - 2015-11-29 07:46:04,419 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:46:04 INFO - 2015-11-29 07:46:04,419 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:46:04 INFO - 2015-11-29 07:46:04,419 DEBUG : BROWSER_OUTPUT: __metrics 07:46:05 INFO - 2015-11-29 07:46:05,502 INFO : Browser initialized. 07:46:05 INFO - 2015-11-29 07:46:05,502 INFO : Running cycle 1/1 for kraken test... 07:46:05 INFO - 2015-11-29 07:46:05,503 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNNcVyQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:46:17 INFO - 2015-11-29 07:46:17,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 138571776 07:46:17 INFO - 2015-11-29 07:46:17,362 DEBUG : BROWSER_OUTPUT: 07:46:19 INFO - 2015-11-29 07:46:19,921 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53234/tests/kraken/driver.html?testName=audio-beat-detection) 07:46:20 INFO - 2015-11-29 07:46:20,543 DEBUG : BROWSER_OUTPUT: RSS: Main: 141512704 07:46:20 INFO - 2015-11-29 07:46:20,544 DEBUG : BROWSER_OUTPUT: 07:46:23 INFO - 2015-11-29 07:46:23,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53234/tests/kraken/driver.html?testName=audio-dft) 07:46:23 INFO - 2015-11-29 07:46:23,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 141070336 07:46:23 INFO - 2015-11-29 07:46:23,854 DEBUG : BROWSER_OUTPUT: 07:46:26 INFO - 2015-11-29 07:46:26,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53234/tests/kraken/driver.html?testName=audio-fft) 07:46:27 INFO - 2015-11-29 07:46:27,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 136785920 07:46:27 INFO - 2015-11-29 07:46:27,360 DEBUG : BROWSER_OUTPUT: 07:46:29 INFO - 2015-11-29 07:46:29,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53234/tests/kraken/driver.html?testName=audio-oscillator) 07:46:30 INFO - 2015-11-29 07:46:30,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 135094272 07:46:30 INFO - 2015-11-29 07:46:30,083 DEBUG : BROWSER_OUTPUT: 07:46:32 INFO - 2015-11-29 07:46:32,336 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:46:32 INFO - 2015-11-29 07:46:32,917 DEBUG : BROWSER_OUTPUT: RSS: Main: 136626176 07:46:32 INFO - 2015-11-29 07:46:32,917 DEBUG : BROWSER_OUTPUT: 07:46:35 INFO - 2015-11-29 07:46:35,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53234/tests/kraken/driver.html?testName=imaging-darkroom) 07:46:36 INFO - 2015-11-29 07:46:36,558 DEBUG : BROWSER_OUTPUT: RSS: Main: 137154560 07:46:36 INFO - 2015-11-29 07:46:36,558 DEBUG : BROWSER_OUTPUT: 07:46:40 INFO - 2015-11-29 07:46:40,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53234/tests/kraken/driver.html?testName=imaging-desaturate) 07:46:40 INFO - 2015-11-29 07:46:40,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 135548928 07:46:40 INFO - 2015-11-29 07:46:40,659 DEBUG : BROWSER_OUTPUT: 07:46:43 INFO - 2015-11-29 07:46:43,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53234/tests/kraken/driver.html?testName=json-parse-financial) 07:46:44 INFO - 2015-11-29 07:46:44,151 DEBUG : BROWSER_OUTPUT: RSS: Main: 136044544 07:46:44 INFO - 2015-11-29 07:46:44,151 DEBUG : BROWSER_OUTPUT: 07:46:45 INFO - 2015-11-29 07:46:45,926 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:46:46 INFO - 2015-11-29 07:46:46,514 DEBUG : BROWSER_OUTPUT: RSS: Main: 137994240 07:46:46 INFO - 2015-11-29 07:46:46,514 DEBUG : BROWSER_OUTPUT: 07:46:48 INFO - 2015-11-29 07:46:48,429 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:46:49 INFO - 2015-11-29 07:46:49,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 137084928 07:46:49 INFO - 2015-11-29 07:46:49,053 DEBUG : BROWSER_OUTPUT: 07:46:51 INFO - 2015-11-29 07:46:51,205 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:46:51 INFO - 2015-11-29 07:46:51,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 137338880 07:46:51 INFO - 2015-11-29 07:46:51,807 DEBUG : BROWSER_OUTPUT: 07:46:54 INFO - 2015-11-29 07:46:54,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:46:54 INFO - 2015-11-29 07:46:54,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 137220096 07:46:54 INFO - 2015-11-29 07:46:54,994 DEBUG : BROWSER_OUTPUT: 07:46:57 INFO - 2015-11-29 07:46:57,597 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:46:58 INFO - 2015-11-29 07:46:58,199 DEBUG : BROWSER_OUTPUT: RSS: Main: 138379264 07:46:58 INFO - 2015-11-29 07:46:58,199 DEBUG : BROWSER_OUTPUT: 07:46:59 INFO - 2015-11-29 07:46:59,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53234/tests/kraken/driver.html?testName=ai-astar) 07:47:00 INFO - 2015-11-29 07:47:00,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 138162176 07:47:00 INFO - 2015-11-29 07:47:00,204 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: |0;ai-astar;118;108;113;111;113;125;113;113;114;113 07:47:00 INFO - 2015-11-29 07:47:00,208 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;142;158;167;133;157;153;137;164;139 07:47:00 INFO - 2015-11-29 07:47:00,209 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;164;162;162;161;162;164;165;164;162 07:47:00 INFO - 2015-11-29 07:47:00,209 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;79;73;72;130;73;79;71;71 07:47:00 INFO - 2015-11-29 07:47:00,209 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;95;137;93;95;94;128;94;94;136 07:47:00 INFO - 2015-11-29 07:47:00,209 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;111;110;109;110;110;110;110;110 07:47:00 INFO - 2015-11-29 07:47:00,209 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;147;147;147;147;148;150;147;148 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;96;97;95;96;96;97;96 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;74;74;76;74;73;74;75;76;71 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;69;69;71;69;70;69;70;69 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;86;83;85;84;85;86;83;83;85 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;128;125;134;130;134;125;127;122;128;131 07:47:00 INFO - 2015-11-29 07:47:00,210 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;154;154;146;152;134;147;147;146;134 07:47:00 INFO - 2015-11-29 07:47:00,211 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;69;73;72;70;69;69;71;69;70 07:47:00 INFO - 2015-11-29 07:47:00,211 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:47:00 INFO - 2015-11-29 07:47:00,211 DEBUG : BROWSER_OUTPUT: __start_cc_report 07:47:00 INFO - 2015-11-29 07:47:00,211 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,783 07:47:00 INFO - 2015-11-29 07:47:00,211 DEBUG : BROWSER_OUTPUT: __end_cc_report 07:47:00 INFO - 2015-11-29 07:47:00,212 DEBUG : BROWSER_OUTPUT: __startTimestamp1448812020206__endTimestamp 07:47:00 INFO - 2015-11-29 07:47:00,212 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,215 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:47:00 INFO - 2015-11-29 07:47:00,215 DEBUG : BROWSER_OUTPUT: Number of tests: 14 07:47:00 INFO - 2015-11-29 07:47:00,215 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,215 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.10 Median:113.00 stddev:4.56 (4.0%) stddev-sans-first:4.61 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: Values: 118.0 108.0 113.0 111.0 113.0 125.0 113.0 113.0 114.0 113.0 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.50 Median:156.00 stddev:11.89 (7.6%) stddev-sans-first:12.50 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: Values: 155.0 142.0 158.0 167.0 133.0 157.0 153.0 137.0 164.0 139.0 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.00 Median:164.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: Values: 164.0 164.0 162.0 162.0 161.0 162.0 164.0 165.0 164.0 162.0 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.10 Median:72.50 stddev:18.14 (25.0%) stddev-sans-first:19.01 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 79.0 73.0 72.0 130.0 73.0 79.0 71.0 71.0 07:47:00 INFO - 2015-11-29 07:47:00,216 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:94.00 stddev:19.24 (20.5%) stddev-sans-first:19.91 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 137.0 93.0 95.0 94.0 128.0 94.0 94.0 136.0 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 111.0 110.0 109.0 110.0 110.0 110.0 110.0 110.0 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 147.0 147.0 147.0 147.0 148.0 150.0 147.0 148.0 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 96.0 97.0 95.0 96.0 96.0 97.0 96.0 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.20 Median:74.50 stddev:1.48 (2.0%) stddev-sans-first:1.54 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 74.0 76.0 74.0 73.0 74.0 75.0 76.0 71.0 07:47:00 INFO - 2015-11-29 07:47:00,217 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:0.71 (1.0%) stddev-sans-first:0.73 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 69.0 69.0 71.0 69.0 70.0 69.0 70.0 69.0 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:1.40 (1.6%) stddev-sans-first:1.24 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: Values: 82.0 86.0 83.0 85.0 84.0 85.0 86.0 83.0 83.0 85.0 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:129.00 stddev:3.92 (3.0%) stddev-sans-first:4.16 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: Values: 128.0 125.0 134.0 130.0 134.0 125.0 127.0 122.0 128.0 131.0 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.10 Median:147.00 stddev:7.65 (5.2%) stddev-sans-first:7.53 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: Values: 137.0 154.0 154.0 146.0 152.0 134.0 147.0 147.0 146.0 134.0 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.50 stddev:1.42 (2.0%) stddev-sans-first:1.48 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: Values: 71.0 69.0 73.0 72.0 70.0 69.0 69.0 71.0 69.0 70.0 07:47:00 INFO - 2015-11-29 07:47:00,218 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:47:00 INFO - 2015-11-29 07:47:00,219 DEBUG : BROWSER_OUTPUT: 07:47:01 INFO - 2015-11-29 07:47:01,138 INFO : Browser exited with error code: 0 07:47:01 INFO - 2015-11-29 07:47:01,190 INFO : Completed test kraken (00:01:01) 07:47:01 INFO - 2015-11-29 07:47:01,190 INFO : Starting test v8_7 07:47:01 INFO - 2015-11-29 07:47:01,190 DEBUG : operating with platform_type : linux_ 07:47:01 INFO - 2015-11-29 07:47:01,190 INFO : Initialising browser for v8_7 test... 07:47:01 INFO - 2015-11-29 07:47:01,196 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVSsiyR/profile http://localhost:53234/getInfo.html 07:47:05 INFO - 2015-11-29 07:47:05,581 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 07:47:05 INFO - 2015-11-29 07:47:05,582 DEBUG : BROWSER_OUTPUT: colorDepth:24 07:47:05 INFO - 2015-11-29 07:47:05,582 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 07:47:05 INFO - 2015-11-29 07:47:05,582 DEBUG : BROWSER_OUTPUT: __metrics 07:47:06 INFO - 2015-11-29 07:47:06,558 INFO : Browser initialized. 07:47:06 INFO - 2015-11-29 07:47:06,558 INFO : Running cycle 1/1 for v8_7 test... 07:47:06 INFO - 2015-11-29 07:47:06,559 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVSsiyR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 07:47:18 INFO - 2015-11-29 07:47:18,410 DEBUG : BROWSER_OUTPUT: RSS: Main: 139313152 07:47:18 INFO - 2015-11-29 07:47:18,410 DEBUG : BROWSER_OUTPUT: 07:47:39 INFO - 2015-11-29 07:47:39,776 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:53234/tests/v8_7/run.html (next: http://localhost:53234/tests/v8_7/run.html) 07:47:40 INFO - 2015-11-29 07:47:40,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 138059776 07:47:40 INFO - 2015-11-29 07:47:40,026 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,026 DEBUG : BROWSER_OUTPUT: __start_tp_report 07:47:40 INFO - 2015-11-29 07:47:40,026 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: |0;Richards;164.14970453053184 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;189.82536066818528 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.0850651149903 07:47:40 INFO - 2015-11-29 07:47:40,027 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5128.205128205128 07:47:40 INFO - 2015-11-29 07:47:40,028 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1068.3760683760684 07:47:40 INFO - 2015-11-29 07:47:40,028 DEBUG : BROWSER_OUTPUT: |5;Earley;702.2471910112359 07:47:40 INFO - 2015-11-29 07:47:40,028 DEBUG : BROWSER_OUTPUT: |6;Boyer;12060.240963855422 07:47:40 INFO - 2015-11-29 07:47:40,028 DEBUG : BROWSER_OUTPUT: |7;RegExp;25475 07:47:40 INFO - 2015-11-29 07:47:40,028 DEBUG : BROWSER_OUTPUT: |8;Splay;619.5786864931846 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: __end_tp_report 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: __startTimestamp1448812060024__endTimestamp 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 07:47:40 INFO - 2015-11-29 07:47:40,029 DEBUG : BROWSER_OUTPUT: Number of tests: 10 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.15 Median:164.15 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: Values: 164.1 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:189.83 Median:189.83 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: Values: 189.8 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.09 Median:277.09 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: Values: 277.1 07:47:40 INFO - 2015-11-29 07:47:40,030 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,031 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5128.21 Median:5128.21 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,031 DEBUG : BROWSER_OUTPUT: Values: 5128.2 07:47:40 INFO - 2015-11-29 07:47:40,031 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,031 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1068.38 Median:1068.38 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,031 DEBUG : BROWSER_OUTPUT: Values: 1068.4 07:47:40 INFO - 2015-11-29 07:47:40,032 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,032 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:702.25 Median:702.25 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,032 DEBUG : BROWSER_OUTPUT: Values: 702.2 07:47:40 INFO - 2015-11-29 07:47:40,032 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12060.24 Median:12060.24 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: Values: 12060.2 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25475.00 Median:25475.00 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: Values: 25475.0 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:619.58 Median:619.58 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: Values: 619.6 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: Values: 4835.7 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 07:47:40 INFO - 2015-11-29 07:47:40,033 DEBUG : BROWSER_OUTPUT: 07:47:40 INFO - 2015-11-29 07:47:40,958 INFO : Browser exited with error code: 0 07:47:40 INFO - 2015-11-29 07:47:40,970 INFO : Completed test v8_7 (00:00:39) 07:47:41 INFO - 2015-11-29 07:47:41,081 INFO : Completed test suite (00:20:15) 07:47:41 INFO - 2015-11-29 07:47:41,082 DEBUG : Working with test: dromaeo_css 07:47:41 INFO - 2015-11-29 07:47:41,082 DEBUG : Generating results file: dromaeo_css 07:47:41 INFO - 2015-11-29 07:47:41,083 DEBUG : Working with test: kraken 07:47:41 INFO - 2015-11-29 07:47:41,083 DEBUG : Generating results file: kraken 07:47:41 INFO - 2015-11-29 07:47:41,083 INFO : javascript benchmark 07:47:41 INFO - 2015-11-29 07:47:41,084 DEBUG : Working with test: v8_7 07:47:41 INFO - 2015-11-29 07:47:41,084 DEBUG : Generating results file: v8_7 07:47:41 INFO - 2015-11-29 07:47:41,084 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:47:41 INFO - 2015-11-29 07:47:41,240 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 07:47:41 INFO - 2015-11-29 07:47:41,240 DEBUG : process_Request line: dromaeo_css 6334.44 graph.html#tests=[[72,132,43]] 07:47:41 INFO - 2015-11-29 07:47:41,240 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:47:41 INFO - 2015-11-29 07:47:41,360 DEBUG : process_Request line: kraken 1538.20 graph.html#tests=[[232,132,43]] 07:47:41 INFO - 2015-11-29 07:47:41,360 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:47:41 INFO - 2015-11-29 07:47:41,412 DEBUG : process_Request line: v8_7 21425.04 graph.html#tests=[[230,132,43]] 07:47:41 INFO - 2015-11-29 07:47:41,414 INFO : javascript benchmark 07:47:41 INFO - 2015-11-29 07:47:41,417 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2655.389260470092, 2718.10302600142, 2749.7937654675893, 2750.4499100179955, 2761.0060378263815, 4526.072155208182, 4640.605548528375, 4648.349231107648, 4654.0, 4681.719096854187, 4511.323301504768, 4553.954460455395, 4592.9665825189395, 4597.103564804865, 4636.281376386657, 4098.159877225162, 4234.259004674177, 4330.97834510827, 4364.825406983717, 4403.0, 4134.855280065199, 4330.177266319405, 4365.083332500163, 4383.167198232326, 4401.031773009932, 4238.258304796648, 4251.404803327537, 4340.08858139791, 4366.497852746943, 4371.562843715635, 4317.373980772802, 4349.913001739979, 4354.54277300882, 4356.847510337123, 4379.671524635665, 4148.274052040911, 4256.489221293465, 4314.80583373749, 4318.373835793824, 4367.781610919441, 4503.684742068057, 4508.2561377372695, 4566.657500687428, 4609.631229501631, 4620.722756634579, 4972.850814475535, 4974.0797952378925, 4999.600031997432, 5006.349174607278, 5006.724630145341, 4801.087793319258, 4928.556429921325, 4964.15609346412, 5015.072211640858, 5038.017586570621, 4522.16339977102, 4558.430196225472, 4571.702839315434, 4581.908361832778, 4624.699394539344, 5077.390713114376, 5118.10433174193, 5131.974340128276, 5136.589072874236, 5192.844214673566, 4701.0, 4762.3570817939135, 4933.161362568372, 4945.653804233741, 4991.775370108354, 4875.049365373789, 4945.2087665973995, 4950.628702847301, 4951.0, 4990.226514890198, 4925.778339974638, 4927.433345165352, 4936.975315123474, 4942.258661200777, 4988.825391111293, 4709.175894218498, 4771.665983381199, 4812.494688057704, 4926.581240594518, 4969.776360063734, 4121.1345617420675, 4130.896727581834, 4135.400366946776, 4147.896302592399, 4150.335946248586, 3662.487251784752, 3663.68858647018, 3665.4135338345736, 3685.262947410529, 3692.095436618046, 4110.979445102756, 4192.622663960258, 4230.598093181143, 4288.935665965013, 4289.420928174656, 4961.05739909416, 4979.228219625964, 4993.525615066563, 4994.850154495371, 5032.84901452957, 4273.401723758676, 4287.378330142112, 4289.442372491619, 4290.099079193405, 4321.027768752068, 4271.231178387858, 4344.45694288214, 4378.584034516716, 4403.911921761547, 4456.955430445719, 4687.554682305175, 4687.99208170245, 4689.999999999932, 4696.882577935579, 4712.340272361872, 4787.569118779326, 4943.110240156806, 4986.80052797884, 4990.80036798524, 5003.649744517848, 4656.068786242833, 4673.719576825401, 4779.976100119477, 4781.784819683192, 4821.035792841376, 4892.094962431962, 4919.950800491949, 4930.285108659153, 4934.407871055496, 4969.602431805519, 4755.548222918817, 4895.657303988686, 4989.625778066587, 5004.274380214775, 5044.672096313728, 4750.714957102585, 4754.168020596313, 4772.785224664829, 4809.927851082167, 4843.782029808737, 4846.103470857903, 4848.927266091082, 4850.345203397496, 4858.514148585114, 4871.634627402888, 4584.289435137559, 4674.5325467452985, 4693.788779504999, 4767.022760334193, 4810.759462026955, 4834.6374021947795, 4901.705897646153, 4910.92633610489, 4930.433000204879, 4960.280759289811, 4779.426468823764, 4789.377380940456, 4806.567408933212, 4819.0121025189055, 4862.246351815475], "unit": "ms", "name": "dojo.html", "value": 4556.861124022978}, {"lowerIsBetter": false, "replicates": [549.6156983983857, 578.4418036594683, 581.9068948968164, 586.2203269651365, 589.8525368657836, 35737.46393804092, 36576.81711591438, 36589.63410365899, 36601.0, 36611.0, 38504.422433663516, 38929.805350973205, 39013.60986390135, 39135.21729565407, 39146.21707565847, 7953.687641539153, 8162.14297498763, 8174.386920980921, 8175.14161013094, 8182.67269309227, 7976.2821346078845, 8057.35541156706, 8059.314958228558, 8071.878921816177, 8115.1073381927945, 7955.920440795576, 8039.598020099001, 8057.395695322845, 8065.637046332899, 8089.433739638228, 8049.919500805005, 8127.837443251102, 8129.0, 8143.429959902809, 8154.143814899409, 7956.164602716747, 8006.079300880415, 8018.759437216893, 8057.879131813028, 8106.148854370265, 35604.28791424183, 36044.81977590122, 36107.0, 36113.097172570895, 36190.63809361899, 2815.7465828075365, 2816.746492815636, 2822.252103192656, 2949.2626843289177, 3361.428557145291, 26023.0, 26023.479530409284, 26213.21360359192, 26222.0, 26393.736062639513, 3440.916111424893, 3450.0857272822723, 3474.1488335358, 3507.57909050913, 3544.9822750886083, 213423.0, 215214.99999999843, 215565.0, 216046.0, 216246.0, 48947.510524895006, 49849.0, 51729.22406163911, 53914.0, 54060.45939540633, 55381.446185538436, 55535.0, 55611.72194139003, 55635.72182139063, 55681.164782527485, 11817.409129543488, 11942.641720748392, 11968.700782480508, 11985.940070299594, 12032.338221397908, 8969.686060987835, 8993.640254389882, 9012.41419307743, 9163.862542061874, 9175.954120229357, 19092.140853661618, 19150.233990640496, 19268.0, 19290.4212873614, 19339.4198174055, 7575.886361704579, 7750.922490775133, 7804.336631386395, 7885.723999659985, 7969.840603187904, 811.1077814404226, 838.6351936907465, 866.9306455483546, 936.7611259128879, 956.5982287439292, 24646.630300545505, 24916.87541562317, 25017.249482515555, 25221.243362699148, 25299.494010119695, 936.1714882329188, 1021.412687704558, 1036.3315661398356, 1040.095117247999, 1122.2480937771713, 13513.594592162252, 14192.645183870485, 14296.928515357356, 14438.494652687315, 14493.855061449462, 316588.0, 319473.0, 320441.0, 320980.0, 321079.0, 46451.0, 46742.0, 46786.0, 46891.29663055057, 46921.0, 1206.6259459567386, 1211.9818202726967, 1216.3614102596032, 1225.185251807538, 1225.3995542184443, 25736.742632574184, 25906.22281331563, 26123.216303510926, 26189.476210475303, 26256.0, 39812.40281395839, 40023.99940001523, 40394.79802600968, 40451.393229101, 40469.39295910505, 249183.0, 249541.0, 250625.0, 250968.0, 251741.0, 28581.714182857904, 29621.407571848875, 29639.0, 29674.406511870075, 29755.702442975293, 37917.241655167294, 38017.4297385545, 38763.612363876004, 39213.2157356857, 39411.40882886702, 32534.0, 32705.672943271216, 33322.0, 33858.49212261867, 33869.83065084659, 33323.1669208262, 33335.49996750002, 33566.83216583901, 33636.66363336335, 33943.151421214665, 33343.83328083344, 33503.32993340071, 33857.322853543286, 34038.48942265918, 34157.63369465194, 28357.716422836333, 29284.414311714074, 29873.551896722, 30317.545236821024, 30436.239094022825, 23255.76744232536, 23677.52644947057, 23835.165769197996, 24046.51906961886, 24070.0], "unit": "ms", "name": "ext.html", "value": 16797.1411529269}, {"lowerIsBetter": false, "replicates": [306.6381669629836, 316.1495576898145, 321.31720094798555, 322.1527382982754, 329.170490364282, 13592.932035339822, 14945.925270373646, 15368.231588420576, 15440.691186176298, 16027.759583606256, 8456.746297611082, 8667.046624871258, 9715.659951901685, 14466.927665361658, 14548.490802821903, 4296.548862369461, 4388.97805510972, 4403.383526306319, 4405.05291362357, 4412.602865742083, 6371.331010243927, 6600.0, 6613.3386661333725, 6618.271990081087, 6631.336866313378, 5938.7624495020145, 6085.452309292163, 6136.785212517563, 6463.709133088999, 6588.835279118012, 6258.999999999977, 6282.623042617434, 6315.842103947415, 6350.142730731384, 6568.934310656928, 6282.183316168915, 6325.272593651697, 6326.272478664966, 6329.272133704637, 6329.556931014831, 19736.70394944077, 20440.080196391198, 20831.791682083138, 21292.680609790867, 21351.35945921609, 1806.1872157529165, 1820.6358728254402, 1851.1484717030182, 1909.9427017189369, 1964.8624596278266, 12753.61739147827, 12901.612951611467, 13208.141470804367, 13338.466461341535, 13620.931895340558, 1850.5743678953781, 1868.5889104397147, 1922.5482011727233, 1961.8136277053657, 1966.6165097805933, 60761.0, 63371.68314158446, 63481.682591586745, 63793.0, 64672.35327646758, 15468.071915685096, 15592.298346574431, 15632.0, 15654.921725391529, 15671.373145074298, 22982.655260171112, 23095.88452057729, 23222.187223447097, 23316.65025024626, 23331.416714582272, 8025.23760242776, 8048.275655191061, 8053.033635963722, 8077.0, 8140.959295203486, 6682.766103186365, 6686.1642294713165, 6690.19717633887, 6690.29751876046, 6699.162604674416, 7610.6194690265265, 7613.5431874087735, 7630.7329243476215, 7637.045369328834, 7638.312551870358, 2877.49643812332, 2891.407261511385, 2955.6453225612636, 2978.9702102979127, 3073.4775088235056, 838.2455789789238, 896.2292428511475, 915.8900931888217, 938.8874184091873, 993.096282383028, 15628.531144065697, 15694.607634808992, 15790.763138552931, 16008.11955342444, 16110.0, 791.6595863778631, 815.995920020396, 848.6181218451721, 876.9429987050821, 909.0954500272271, 7258.709651613982, 8732.912670873337, 9044.954775226082, 9173.312001599907, 9230.769230769285, 339722.0, 345531.0, 507864.0, 510877.0, 515321.0, 74360.25639743534, 75386.24613753903, 76122.6193869027, 76712.61643691857, 76905.61547192228, 1018.5467466977273, 1028.2493779540978, 1029.8867124616286, 1043.8642976413018, 1055.72551136705, 14572.635684107981, 14643.26783660834, 14660.0, 14680.0, 14792.11247325164, 32466.188345291557, 33122.66877331196, 33359.166020849676, 33445.498317525744, 34245.657543425245, 43028.0, 43629.345559817266, 44486.555134448245, 44684.776576116914, 44728.10543789041, 7958.3235424989525, 8699.652013919625, 8700.12998700125, 8705.956470217609, 8723.738287851374, 13861.79207311871, 15211.239438028275, 15703.13632750164, 15980.600484987503, 16206.189690515192, 13582.59252222435, 13611.183328999896, 13748.243846588533, 13755.862441375459, 14043.719125617226, 13105.14816536922, 13236.0, 13249.0, 13261.469541218243, 13625.386857591235, 13327.0, 13443.126196797177, 13530.729385412433, 13624.455021799016, 13808.930955345159, 7516.774496765106, 7740.26467485588, 7748.496347737379, 7954.482958607672, 8072.87890681652, 8482.363822713198, 8607.82784344297, 13406.597802065953, 13515.932420337835, 13821.516246931711], "unit": "ms", "name": "jquery.html", "value": 9027.58023664136}, {"lowerIsBetter": false, "replicates": [1344.3412727763398, 1349.7705390083686, 1394.177435313165, 1566.2560283865162, 1616.345380121051, 5595.4124816894255, 5928.199693041438, 6480.31956644552, 6745.494087943405, 6754.560953538023, 6281.371862813717, 6495.155629768124, 6525.249596296428, 6536.248331441886, 6573.962071757758, 1938.835199008086, 1982.672858978274, 1983.9404817855489, 1985.6922177062506, 1985.7319261899675, 1922.8365588924958, 1945.9221631134737, 1964.0, 1986.4437957371886, 1994.6908229224493, 1961.9411417657493, 1966.2724791827045, 1986.9105890234973, 2002.6895831146196, 2003.3188715836613, 1951.0829909942304, 1954.9609007819836, 1960.5000724815222, 1965.518447980254, 1967.4491142480128, 1926.5376309685714, 1954.2769175405192, 1963.3619073801071, 1969.094216660338, 1969.3993332033724, 6567.376099270562, 6593.044008618724, 6629.834254143685, 6637.303083176245, 6642.7342906284175, 3784.2242340320167, 3856.3636999895266, 3859.671927886133, 3859.9228015439817, 3862.092408284051, 2149.7420309562795, 2188.070070220166, 2465.0879174705296, 2517.9118730844516, 2522.495500899809, 804.3766081286991, 828.337330135895, 842.4650347029619, 842.9241368276885, 881.4226681523612, 8714.25928796053, 8726.825463490759, 8734.2139207471, 8749.212570868589, 8784.341174411944, 3453.8273086346085, 3456.8962931112105, 3527.6295988921306, 3540.9645903540636, 3560.537130173113, 7515.661795219119, 7540.698372065166, 7573.962130189424, 7579.204183560757, 7586.051743532059, 3759.0790256386813, 3760.8119594020736, 3772.358699021173, 3778.6410291022307, 3797.240551889633, 3408.2672225471642, 3444.156181735491, 3444.4488881778793, 3445.431503801895, 3448.3965306071336, 3175.364927014606, 3185.8566364513654, 3189.1389324882616, 3210.7110360067704, 3217.3082787200865, 962.5860879821604, 969.8739163908788, 972.2805124208177, 972.9756756081155, 979.3340528440586, 1169.1173164260929, 1180.1797750563276, 1181.4388165621433, 1194.5341316886422, 1206.330486579939, 6018.127371531191, 6119.877602447926, 6194.5973511721595, 6196.039613859769, 6198.876022479525, 941.5527624378366, 959.6449313753816, 962.4706411473801, 1040.54216144896, 1062.9574817007388, 5075.467075956972, 5083.694978301313, 5094.49055094495, 5102.515261050195, 5115.258287548285, 7018.2630823762765, 7045.401140903081, 7621.275978782007, 7636.121845987783, 7688.0, 7456.956026156343, 7462.85074298511, 7467.589282589401, 7487.925120748831, 7500.512466689648, 6218.129461875341, 6234.781782637586, 6306.558540902093, 6325.525585581007, 6358.650274234869, 6248.68756562161, 6260.280067792262, 6312.43188113072, 6334.778282760081, 6334.841628959313, 6404.551681382445, 6472.611643301417, 6582.012698095133, 7031.43748500129, 7034.050403195503, 7020.473464490082, 7050.15398152225, 7187.6765545551625, 7210.206877243604, 7287.635618218962, 5927.347991720821, 5946.167536544974, 5999.370066142992, 6001.639901605918, 6018.127371531016, 5934.050551911673, 6141.7543298267565, 6337.556371053982, 6352.428281454691, 6379.266384365672, 5944.5838791284195, 6024.668643224665, 6027.668478233739, 6048.848778780566, 6059.424354686298, 5903.763849446146, 5940.524758019435, 5945.91081133792, 6019.849503762441, 6089.451949324582, 5953.434423729739, 5971.373005834324, 5980.671063091571, 5989.910151347646, 6017.638941663514, 5502.312210973629, 5584.636998595241, 5598.832035038955, 5619.494245517923, 5625.212470254086, 5641.492265696107, 5666.886662266649, 5674.716264186856, 5681.289838770153, 5713.771449141987], "unit": "ms", "name": "mootools.html", "value": 3809.903047529633}, {"lowerIsBetter": false, "replicates": [285.9142257322803, 287.1915557705061, 287.9524878395065, 291.5961393470044, 300.8495752123938, 5450.56395488361, 5501.312335958005, 5563.610547261694, 5567.972160139194, 5633.8309850704445, 5471.343438787351, 5544.417836127209, 5547.833564993057, 5554.222408862763, 5567.4432556744305, 4239.830406783725, 4241.639460645849, 4241.787910604465, 4249.25638013348, 4250.744955302676, 4137.834486620532, 4192.7840926131385, 4195.895102622444, 4207.263728847454, 4265.8080386382535, 4186.309258972281, 4205.327147656376, 4205.537390887, 4212.031232816454, 4220.219259436999, 4202.3486359614, 4207.51613564441, 4213.7893105344765, 4217.472815898012, 4222.662187025031, 3691.1141326081815, 3691.169486865461, 3691.280200360931, 3697.149655579232, 3698.0754811297174, 5188.481151884819, 5220.947790522122, 5328.893422131575, 5347.41178470368, 5380.977614253279, 4878.07316609843, 4888.095702295087, 4893.21708526634, 4894.4371397289415, 4896.4858689837765, 5814.418558144152, 5827.4172582741385, 5828.475437210629, 5836.649801011953, 5837.678927658977, 4045.0494133878515, 4051.3315302974975, 4054.3310353791885, 4057.046594050396, 4065.735727177724, 5854.707264636793, 6073.696315184267, 6084.0, 6089.360617135181, 6096.4817990470865, 4178.247915375201, 4185.727927684691, 4188.350805625133, 4191.874243772691, 4194.035371864488, 4249.936250956238, 4252.149570085995, 4253.957460425418, 4256.7445953242905, 4260.020195355086, 4854.757262136879, 4858.1012512685265, 4858.73276969763, 4859.902801943941, 4869.659123861366, 5595.776168953278, 5746.626469279484, 5776.306843178845, 5798.971005144947, 5803.593748437654, 1457.7084583083426, 1487.9776803347959, 1488.3153749275239, 1491.6569189086572, 1493.089215578518, 1013.5793645636978, 1027.732789474727, 1034.0848349210853, 1043.1498328861953, 1053.3258714422775, 4962.503749625081, 4978.8257410990445, 4989.725565093882, 5014.648974571818, 5026.2460630904925, 5997.910031349533, 5999.5800293979, 6011.549133814981, 6023.759049638053, 6028.125921741323, 4986.975065124651, 4992.925106123411, 5015.297858299841, 5018.774155163026, 5023.773930173151, 3162.2410621450686, 3201.983990080035, 3224.500202468621, 3234.385466761355, 3236.9838150809564, 5582.0, 5608.69152196625, 5610.046292130348, 5615.887682246333, 5634.7182640867795, 6109.358517355614, 6238.313785483593, 6239.06414037898, 6245.344238854855, 6260.718267677966, 5628.521575666033, 5640.5487560995025, 5690.772369105272, 5699.857503562361, 5702.8289151325525, 6059.606125601822, 6071.27144742616, 6072.514198864169, 6078.179445774764, 6092.817215483543, 5765.336986246468, 5929.0513517836935, 5941.702914854326, 5943.791967281298, 5945.048792193228, 5685.459881311269, 5689.857753556194, 5691.715414229355, 5698.0598201296325, 5702.144678298288, 3968.980155099206, 4033.9193216134927, 4042.191561687733, 4044.8786536403954, 4052.7639070083906, 5901.232839730807, 5995.280566332068, 6039.637621742534, 6043.12374705674, 6053.545984051126, 5822.854428639318, 5836.241288632451, 5840.357560668238, 5845.678487683048, 5845.736941837543, 5843.766249349978, 5847.0, 5852.063669812809, 5859.091840764689, 5859.707014649336, 5579.644447948393, 5729.799457018984, 5752.654840709571, 5758.769649213985, 5760.107183386582, 5821.359650438364, 5873.823785286449, 5874.500667443315, 5877.500412464989, 5889.646621202742, 5697.601167918206, 5728.971355143198, 5731.971340143273, 5738.68437235956, 5761.107028410603], "unit": "ms", "name": "prototype.html", "value": 4343.1610617854}, {"lowerIsBetter": false, "replicates": [784.7175016993884, 786.2530595933861, 788.1015642167926, 788.9447738658295, 797.1629788721842, 9932.0, 10009.0, 10049.799004019915, 10058.647947321837, 10077.496125193748, 10096.596136154563, 10259.589616415353, 10281.485925703704, 10300.07299343061, 10300.793984120313, 598.9071693887454, 607.2045620237503, 752.6613024139145, 752.947293689442, 766.9309762121408, 767.1446337333888, 769.2845653542204, 774.2644487736644, 774.2799196747023, 774.3843644302765, 803.120582961656, 810.8864758933754, 811.2374368094003, 811.3265989228955, 811.6104269950428, 797.948133371329, 799.6521513141802, 801.218811658634, 802.1978021978022, 805.4563169860351, 593.715016791938, 595.553334998751, 597.4145337569206, 597.6025942748067, 598.2282855116938, 10225.642102526375, 10277.075063244269, 10289.176865850714, 10292.433916134609, 10305.742356441075, 11167.0, 11280.2667826591, 11377.772444551145, 11596.942015289955, 11679.532818687243, 9204.907950920538, 9387.061293870558, 9474.147326740627, 9485.857712134324, 9527.333086683935, 3084.5373194020854, 3091.381723655278, 3134.3417882244757, 3163.114327988167, 3170.5719727836677, 40584.0, 40629.796851015555, 40632.79683601563, 40695.0, 40774.79612601918, 9108.635654573874, 9243.445393276425, 9272.350935434455, 9377.015413381632, 9405.106514881076, 16109.0, 16119.919400402923, 16125.0, 16163.838361616468, 16203.189840507928, 6343.555951083472, 6370.0, 6473.385028422293, 6611.834704132435, 6646.468282537386, 3524.5770507538746, 3623.4745961835915, 3629.909252268714, 3636.3636363636256, 3656.8902932912056, 2593.6757905261843, 2631.2763989902546, 2632.2498088044817, 2639.8152129351147, 2649.152271273174, 468.76327454635873, 473.3515084334415, 473.4082397003745, 488.6213184781823, 490.3380436410888, 547.8329109621603, 576.7981206577748, 612.6385432594791, 623.4918541388754, 637.4486069549806, 7541.886871696929, 7691.153973062958, 7871.291583757489, 8230.670773169126, 8321.791955201168, 483.13036534238233, 489.7355428068874, 526.6524094097877, 596.9820905372845, 650.2944305428568, 3474.2183008822813, 3543.53933988585, 3645.143391303042, 3688.207035487383, 3768.2275133597545, 46338.5366146341, 46825.5317446828, 49654.503454965714, 50293.497065029616, 50319.74840125776, 44031.779841100586, 44755.0, 44835.77582112069, 45439.772801136445, 45559.77220113944, 41374.37938430926, 41378.58621413808, 41713.0, 41734.791326043785, 42055.7897210512, 7839.843203135792, 7853.504609039047, 8017.919820801717, 8233.79415514617, 8338.24955753985, 31049.68950310468, 31742.0, 31915.680843191272, 33201.66798331986, 34089.65910340964, 38637.42043869288, 39084.2183156341, 39439.80280098696, 39574.40638390484, 40145.1970960585, 9281.350305478822, 9613.134817866425, 9805.313628046253, 10092.798144036931, 10161.745956351151, 11494.770104598028, 11640.243384180001, 11744.765104697688, 11835.230710004167, 12421.0, 9869.703908882744, 9939.055789699967, 10849.294795838245, 10918.945405272923, 11119.722006949567, 9795.510224488604, 9969.0, 10696.0, 10831.350118992885, 11446.828297575377, 9186.40288381253, 9955.751106222113, 10136.290459667753, 11041.282316649393, 11442.713932151762, 9541.618335266512, 9725.173360264456, 9748.85376719364, 9957.203423726229, 10008.09927106564, 9526.333156678966, 9601.903980960102, 9627.374220675914, 9645.42127472354, 9779.462129582656], "unit": "ms", "name": "yui.html", "value": 5649.507069029948}], "name": "dromaeo_css", "value": 6334.445650668259}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [118.0, 108.0, 113.0, 111.0, 113.0, 125.0, 113.0, 113.0, 114.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 114.1}, {"lowerIsBetter": true, "replicates": [155.0, 142.0, 158.0, 167.0, 133.0, 157.0, 153.0, 137.0, 164.0, 139.0], "unit": "ms", "name": "audio-beat-detection", "value": 150.5}, {"lowerIsBetter": true, "replicates": [164.0, 164.0, 162.0, 162.0, 161.0, 162.0, 164.0, 165.0, 164.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 163.0}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 79.0, 73.0, 72.0, 130.0, 73.0, 79.0, 71.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 79.1}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 137.0, 93.0, 95.0, 94.0, 128.0, 94.0, 94.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 150.0, 147.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [75.0, 74.0, 74.0, 76.0, 74.0, 73.0, 74.0, 75.0, 76.0, 71.0], "unit": "ms", "name": "json-parse-financial", "value": 74.2}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 69.0, 71.0, 69.0, 70.0, 69.0, 70.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.5}, {"lowerIsBetter": true, "replicates": [82.0, 86.0, 83.0, 85.0, 84.0, 85.0, 86.0, 83.0, 83.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.2}, {"lowerIsBetter": true, "replicates": [128.0, 125.0, 134.0, 130.0, 134.0, 125.0, 127.0, 122.0, 128.0, 131.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.4}, {"lowerIsBetter": true, "replicates": [137.0, 154.0, 154.0, 146.0, 152.0, 134.0, 147.0, 147.0, 146.0, 134.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.1}, {"lowerIsBetter": true, "replicates": [71.0, 69.0, 73.0, 72.0, 70.0, 69.0, 69.0, 71.0, 69.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.3}], "name": "kraken", "value": 1538.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.14970453053184], "unit": "score", "name": "Richards", "value": 215.0597840000001}, {"lowerIsBetter": false, "replicates": [189.82536066818528], "unit": "score", "name": "DeltaBlue", "value": 348.30962399999987}, {"lowerIsBetter": false, "replicates": [277.0850651149903], "unit": "score", "name": "Encrypt", "value": 960.647229}, {"lowerIsBetter": false, "replicates": [5128.205128205128], "unit": "score", "name": "Decrypt", "value": 51.90529499999997}, {"lowerIsBetter": false, "replicates": [1068.3760683760684], "unit": "score", "name": "RayTrace", "value": 692.6297040000002}, {"lowerIsBetter": false, "replicates": [702.2471910112359], "unit": "score", "name": "Earley", "value": 949.0433120000004}, {"lowerIsBetter": false, "replicates": [12060.240963855422], "unit": "score", "name": "Boyer", "value": 55.2611678321678}, {"lowerIsBetter": false, "replicates": [25475.0], "unit": "score", "name": "RegExp", "value": 35.759960745829254}, {"lowerIsBetter": false, "replicates": [619.5786864931846], "unit": "score", "name": "Splay", "value": 131.52647400000006}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 21425.041542330557}]} 07:47:41 INFO - RETURN: dromaeo_css: 6334.44 07:47:41 INFO - RETURN: kraken: 1538.20 07:47:41 INFO - RETURN: v8_7: 21425.04 07:47:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21425.04"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1538.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6334.44"}}} 07:47:41 INFO - Return code: 0 07:47:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:47:41 INFO - # TBPL SUCCESS # 07:47:41 INFO - Running post-action listener: _resource_record_post_action 07:47:41 INFO - Running post-run listener: _resource_record_post_run 07:47:42 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 16384; Write bytes: 512765952; Read time: 48; Write time: 437680 07:47:42 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239661056; Read time: 0; Write time: 259532 07:47:42 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 16384; Write bytes: 273104896; Read time: 48; Write time: 178148 07:47:42 INFO - Running post-run listener: _upload_blobber_files 07:47:42 INFO - Blob upload gear active. 07:47:42 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:47:42 INFO - Copying logs to upload dir... 07:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.986600 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2015-11-29 07:47:42.635621) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-29 07:47:42.638776) ========= 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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010026 build_url: 'https://queue.taskcluster.net/v1/task/2b6xjxtkTwal7rG4S3Uvrg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-29 07:47:42.690408) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:47:42.690730) ========= 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=21869f6c619b95355032545b000001da-1448805477.55411-1061541873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-29 07:47:42.741086) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-29 07:47:42.741579) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-29 07:47:42.741905) ========= ========= Total master_lag: 0.22 =========