builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-008 starttime: 1448417181.35 results: success (0) buildid: 20151124170931 builduid: fee7bdcee91a460c814c57909cb6ceeb revision: f39db2865764809d45ea45f70e1ea0d56bdcc082 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.350811) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.351247) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.351573) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006539 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.396660) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.396964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.442002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.442281) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004696 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.497713) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.498029) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.498378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.498661) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 18:06:21-- 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% 34.2M=0s 2015-11-24 18:06:21 (34.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190414 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.701589) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.701921) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020329 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.752077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:21.752498) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f39db2865764809d45ea45f70e1ea0d56bdcc082 --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 f39db2865764809d45ea45f70e1ea0d56bdcc082 --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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 18:06:22,104 truncating revision to first 12 chars 2015-11-24 18:06:22,104 Setting DEBUG logging. 2015-11-24 18:06:22,104 attempt 1/10 2015-11-24 18:06:22,104 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f39db2865764?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 18:06:22,525 unpacking tar archive at: fx-team-f39db2865764/testing/mozharness/ program finished with exit code 0 elapsedTime=0.707554 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:22.472673) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:22.472972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:22.481381) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:22.481701) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 18:06:22.482156) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 18:06:22.482495) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:06:22 INFO - MultiFileLogger online at 20151124 18:06:22 in /builds/slave/test 18:06:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --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 18:06:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:06:22 INFO - {'append_to_log': False, 18:06:22 INFO - 'base_work_dir': '/builds/slave/test', 18:06:22 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 18:06:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:06:22 INFO - 'branch': 'Fx-Team-Non-PGO', 18:06:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:06:22 INFO - 'config_files': ('talos/linux_config.py',), 18:06:22 INFO - 'default_actions': ('clobber', 18:06:22 INFO - 'read-buildbot-config', 18:06:22 INFO - 'download-and-extract', 18:06:22 INFO - 'populate-webroot', 18:06:22 INFO - 'create-virtualenv', 18:06:22 INFO - 'install', 18:06:22 INFO - 'run-tests'), 18:06:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:06:22 INFO - 'download_minidump_stackwalk': True, 18:06:22 INFO - 'download_symbols': 'ondemand', 18:06:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:06:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:06:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:06:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:06:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:06:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:06:22 INFO - 'installer_path': 'installer.exe', 18:06:22 INFO - 'log_level': 'info', 18:06:22 INFO - 'log_name': 'talos', 18:06:22 INFO - 'log_to_console': True, 18:06:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:06:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:06:22 INFO - 'opt_config_files': (), 18:06:22 INFO - 'pip_index': False, 18:06:22 INFO - 'sps_profile': False, 18:06:22 INFO - 'sps_profile_interval': 0, 18:06:22 INFO - 'suite': 'dromaeojs', 18:06:22 INFO - 'system_bits': '64', 18:06:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:06:22 INFO - 'title': 'talos-linux64-ix-008', 18:06:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:06:22 INFO - 'use_talos_json': True, 18:06:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:06:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:06:22 INFO - 'work_dir': 'build'} 18:06:22 INFO - ##### 18:06:22 INFO - ##### Running clobber step. 18:06:22 INFO - ##### 18:06:22 INFO - Running pre-action listener: _resource_record_pre_action 18:06:22 INFO - Running main action method: clobber 18:06:22 INFO - rmtree: /builds/slave/test/build 18:06:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:06:23 INFO - Running post-action listener: _resource_record_post_action 18:06:23 INFO - ##### 18:06:23 INFO - ##### Running read-buildbot-config step. 18:06:23 INFO - ##### 18:06:23 INFO - Running pre-action listener: _resource_record_pre_action 18:06:23 INFO - Running main action method: read_buildbot_config 18:06:23 INFO - Using buildbot properties: 18:06:23 INFO - { 18:06:23 INFO - "properties": { 18:06:23 INFO - "buildnumber": 2120, 18:06:23 INFO - "product": "firefox", 18:06:23 INFO - "script_repo_revision": "production", 18:06:23 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:06:23 INFO - "repository": "", 18:06:23 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 18:06:23 INFO - "buildid": "20151124170931", 18:06:23 INFO - "slavename": "talos-linux64-ix-008", 18:06:23 INFO - "pgo_build": "False", 18:06:23 INFO - "basedir": "/builds/slave/test", 18:06:23 INFO - "project": "", 18:06:23 INFO - "platform": "ubuntu64_hw", 18:06:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:06:23 INFO - "slavebuilddir": "test", 18:06:23 INFO - "scheduler": "tests-fx-team-linux64-talos", 18:06:23 INFO - "branch": "fx-team", 18:06:23 INFO - "repo_path": "integration/fx-team", 18:06:23 INFO - "stage_platform": "linux64", 18:06:23 INFO - "builduid": "fee7bdcee91a460c814c57909cb6ceeb", 18:06:23 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 18:06:23 INFO - }, 18:06:23 INFO - "sourcestamp": { 18:06:23 INFO - "repository": "", 18:06:23 INFO - "hasPatch": false, 18:06:23 INFO - "project": "", 18:06:23 INFO - "branch": "fx-team-linux64-talos", 18:06:23 INFO - "changes": [ 18:06:23 INFO - { 18:06:23 INFO - "category": null, 18:06:23 INFO - "files": [ 18:06:23 INFO - { 18:06:23 INFO - "url": null, 18:06:23 INFO - "name": "https://queue.taskcluster.net/v1/task/UV8YHYjPQAqFjbyHXnGDGA/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:06:23 INFO - } 18:06:23 INFO - ], 18:06:23 INFO - "repository": "", 18:06:23 INFO - "rev": "95ac4f70df1b1d6ca8a0695acaec8510f41b6a6e", 18:06:23 INFO - "who": "kwierso@gmail.com", 18:06:23 INFO - "when": 1448417162, 18:06:23 INFO - "number": 6703009, 18:06:23 INFO - "comments": "Merge m-c to fx-team, a=merge", 18:06:23 INFO - "project": "", 18:06:23 INFO - "at": "Tue 24 Nov 2015 18:06:02", 18:06:23 INFO - "branch": "fx-team-linux64-talos", 18:06:23 INFO - "revlink": "", 18:06:23 INFO - "properties": [ 18:06:23 INFO - [ 18:06:23 INFO - "buildid", 18:06:23 INFO - "20151124165733", 18:06:23 INFO - "Change" 18:06:23 INFO - ], 18:06:23 INFO - [ 18:06:23 INFO - "builduid", 18:06:23 INFO - "1c2b72991ed9411ab7b988010a2c102e", 18:06:23 INFO - "Change" 18:06:23 INFO - ], 18:06:23 INFO - [ 18:06:23 INFO - "pgo_build", 18:06:23 INFO - "False", 18:06:23 INFO - "Change" 18:06:23 INFO - ] 18:06:23 INFO - ], 18:06:23 INFO - "revision": "95ac4f70df1b1d6ca8a0695acaec8510f41b6a6e" 18:06:23 INFO - }, 18:06:23 INFO - { 18:06:23 INFO - "category": null, 18:06:23 INFO - "files": [ 18:06:23 INFO - { 18:06:23 INFO - "url": null, 18:06:23 INFO - "name": "https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:06:23 INFO - } 18:06:23 INFO - ], 18:06:23 INFO - "repository": "", 18:06:23 INFO - "rev": "f39db2865764809d45ea45f70e1ea0d56bdcc082", 18:06:23 INFO - "who": "jwein@mozilla.com", 18:06:23 INFO - "when": 1448417100, 18:06:23 INFO - "number": 6703002, 18:06:23 INFO - "comments": "Bug 1227717 - Tweak the margin-end on the gear button on Windows to better align with the alert close button. r=MattN", 18:06:23 INFO - "project": "", 18:06:23 INFO - "at": "Tue 24 Nov 2015 18:05:00", 18:06:23 INFO - "branch": "fx-team-linux64-talos", 18:06:23 INFO - "revlink": "", 18:06:23 INFO - "properties": [ 18:06:23 INFO - [ 18:06:23 INFO - "buildid", 18:06:23 INFO - "20151124170931", 18:06:23 INFO - "Change" 18:06:23 INFO - ], 18:06:23 INFO - [ 18:06:23 INFO - "builduid", 18:06:23 INFO - "fee7bdcee91a460c814c57909cb6ceeb", 18:06:23 INFO - "Change" 18:06:23 INFO - ], 18:06:23 INFO - [ 18:06:23 INFO - "pgo_build", 18:06:23 INFO - "False", 18:06:23 INFO - "Change" 18:06:23 INFO - ] 18:06:23 INFO - ], 18:06:23 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 18:06:23 INFO - } 18:06:23 INFO - ], 18:06:23 INFO - "revision": "f39db2865764809d45ea45f70e1ea0d56bdcc082" 18:06:23 INFO - } 18:06:23 INFO - } 18:06:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:06:23 INFO - Running post-action listener: _resource_record_post_action 18:06:23 INFO - ##### 18:06:23 INFO - ##### Running download-and-extract step. 18:06:23 INFO - ##### 18:06:23 INFO - Running pre-action listener: _resource_record_pre_action 18:06:23 INFO - Running main action method: download_and_extract 18:06:23 INFO - mkdir: /builds/slave/test/build/tests 18:06:23 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')]} 18:06:23 INFO - https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:06:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/test_packages.json 18:06:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/test_packages.json 18:06:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:06:26 INFO - Downloaded 1302 bytes. 18:06:26 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:06:26 INFO - Using the following test package requirements: 18:06:26 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:06:26 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:06:26 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'jsshell-linux-x86_64.zip'], 18:06:26 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:06:26 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:06:26 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:06:26 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:06:26 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:06:26 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:06:26 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:06:26 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:06:26 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:06:26 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')]} 18:06:26 INFO - https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:06:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:27 INFO - Downloaded 21345683 bytes. 18:06:27 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 18:06:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:27 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 18:06:28 INFO - Return code: 0 18:06:28 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 18:06:28 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')]} 18:06:28 INFO - https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:06:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:32 INFO - Downloaded 21345683 bytes. 18:06:32 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 18:06:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:06:32 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 18:06:33 INFO - Return code: 0 18:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:33 INFO - https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:06:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:06:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:06:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:34 INFO - Downloaded 11131717 bytes. 18:06:34 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 18:06:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:06:34 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 18:06:34 INFO - Return code: 0 18:06:34 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')]} 18:06:34 INFO - https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:06:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:06:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:06:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-GjJps0-SLCPUOQHPQ_how/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 18:06:36 INFO - Downloaded 56845866 bytes. 18:06:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:06:36 INFO - mkdir: /builds/slave/test/properties 18:06:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:06:36 INFO - Writing to file /builds/slave/test/properties/build_url 18:06:36 INFO - Contents: 18:06:36 INFO - build_url:https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:06:36 INFO - Running post-action listener: _resource_record_post_action 18:06:36 INFO - Running post-action listener: set_extra_try_arguments 18:06:36 INFO - ##### 18:06:36 INFO - ##### Running populate-webroot step. 18:06:36 INFO - ##### 18:06:36 INFO - Running pre-action listener: _resource_record_pre_action 18:06:36 INFO - Running main action method: populate_webroot 18:06:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:06:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:06:36 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:06:36 INFO - '../fennec_ids.txt'], 18:06:36 INFO - 'tests': ['tcheck2']}, 18:06:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:06:36 INFO - '--tppagecycles', 18:06:36 INFO - '7'], 18:06:36 INFO - 'tests': ['tsvgm']}}, 18:06:36 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:06:36 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:06:36 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:06:36 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:06:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:06:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:06:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'tests': ['damp', 'tps']}, 18:06:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['damp', 'tps']}, 18:06:36 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:06:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:06:36 INFO - 'other': {'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'other_l64': {'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'other_nol64': {'tests': ['a11yr', 18:06:36 INFO - 'ts_paint', 18:06:36 INFO - 'tpaint', 18:06:36 INFO - 'sessionrestore', 18:06:36 INFO - 'sessionrestore_no_auto_restore']}, 18:06:36 INFO - 'svgr': {'tests': ['tsvgx', 18:06:36 INFO - 'tsvgr_opacity', 18:06:36 INFO - 'tart', 18:06:36 INFO - 'tscrollx', 18:06:36 INFO - 'cart']}, 18:06:36 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['tsvgx', 18:06:36 INFO - 'tsvgr_opacity', 18:06:36 INFO - 'tart', 18:06:36 INFO - 'tscrollx', 18:06:36 INFO - 'cart']}, 18:06:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'tests': ['tp5o']}, 18:06:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'talos_options': ['--e10s'], 18:06:36 INFO - 'tests': ['tp5o']}, 18:06:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'talos_options': ['--xperf_path', 18:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:06:36 INFO - 'tests': ['tp5n']}, 18:06:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:06:36 INFO - 'talos_options': ['--e10s', 18:06:36 INFO - '--xperf_path', 18:06:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:06:36 INFO - 'tests': ['tp5n']}}, 18:06:36 INFO - 'talos.zip': {'path': '', 18:06:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:06:36 INFO - Running post-action listener: _resource_record_post_action 18:06:36 INFO - ##### 18:06:36 INFO - ##### Running create-virtualenv step. 18:06:36 INFO - ##### 18:06:36 INFO - Running pre-action listener: _resource_record_pre_action 18:06:36 INFO - Running main action method: create_virtualenv 18:06:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:06:36 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 18:06:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:06:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:06:36 INFO - Using real prefix '/usr' 18:06:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:06:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:06:38 INFO - Installing pip.................done. 18:06:38 INFO - Return code: 0 18:06:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:06:38 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')]} 18:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:38 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')]} 18:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:38 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:38 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 18:06:38 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 18:06:38 INFO - Using env: {'DISPLAY': ':0', 18:06:38 INFO - 'HOME': '/home/cltbld', 18:06:38 INFO - 'LANG': 'en_US.UTF-8', 18:06:38 INFO - 'LANGUAGE': 'en_US:en', 18:06:38 INFO - 'LOGNAME': 'cltbld', 18:06:38 INFO - 'MAIL': '/var/mail/cltbld', 18:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:38 INFO - 'MOZ_NO_REMOTE': '1', 18:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:38 INFO - 'NO_EM_RESTART': '1', 18:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:38 INFO - 'PWD': '/builds/slave/test', 18:06:38 INFO - 'SHELL': '/bin/bash', 18:06:38 INFO - 'SHLVL': '1', 18:06:38 INFO - 'TERM': 'linux', 18:06:38 INFO - 'TMOUT': '86400', 18:06:38 INFO - 'USER': 'cltbld', 18:06:38 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:38 INFO - '_': '/tools/buildbot/bin/python'} 18:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:39 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 18:06:39 INFO - Cleaning up... 18:06:39 INFO - Return code: 0 18:06:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:39 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:39 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 18:06:39 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 18:06:39 INFO - Using env: {'DISPLAY': ':0', 18:06:39 INFO - 'HOME': '/home/cltbld', 18:06:39 INFO - 'LANG': 'en_US.UTF-8', 18:06:39 INFO - 'LANGUAGE': 'en_US:en', 18:06:39 INFO - 'LOGNAME': 'cltbld', 18:06:39 INFO - 'MAIL': '/var/mail/cltbld', 18:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:39 INFO - 'MOZ_NO_REMOTE': '1', 18:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:39 INFO - 'NO_EM_RESTART': '1', 18:06:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:39 INFO - 'PWD': '/builds/slave/test', 18:06:39 INFO - 'SHELL': '/bin/bash', 18:06:39 INFO - 'SHLVL': '1', 18:06:39 INFO - 'TERM': 'linux', 18:06:39 INFO - 'TMOUT': '86400', 18:06:39 INFO - 'USER': 'cltbld', 18:06:39 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:39 INFO - '_': '/tools/buildbot/bin/python'} 18:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:39 INFO - Downloading/unpacking psutil>=0.7.1 18:06:39 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 18:06:39 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 18:06:39 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 18:06:39 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 18:06:39 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 18:06:39 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 18:06:40 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:06:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:06:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:06:41 INFO - Installing collected packages: psutil 18:06:41 INFO - Running setup.py install for psutil 18:06:41 INFO - building 'psutil._psutil_linux' extension 18:06:41 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 18:06:41 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 18:06:41 INFO - building 'psutil._psutil_posix' extension 18:06:41 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 18:06:41 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 18:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:06:41 INFO - Successfully installed psutil 18:06:41 INFO - Cleaning up... 18:06:41 INFO - Return code: 0 18:06:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:41 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:41 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 18:06:41 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 18:06:41 INFO - Using env: {'DISPLAY': ':0', 18:06:41 INFO - 'HOME': '/home/cltbld', 18:06:41 INFO - 'LANG': 'en_US.UTF-8', 18:06:41 INFO - 'LANGUAGE': 'en_US:en', 18:06:41 INFO - 'LOGNAME': 'cltbld', 18:06:41 INFO - 'MAIL': '/var/mail/cltbld', 18:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:41 INFO - 'MOZ_NO_REMOTE': '1', 18:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:41 INFO - 'NO_EM_RESTART': '1', 18:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:41 INFO - 'PWD': '/builds/slave/test', 18:06:41 INFO - 'SHELL': '/bin/bash', 18:06:41 INFO - 'SHLVL': '1', 18:06:41 INFO - 'TERM': 'linux', 18:06:41 INFO - 'TMOUT': '86400', 18:06:41 INFO - 'USER': 'cltbld', 18:06:41 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:41 INFO - '_': '/tools/buildbot/bin/python'} 18:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:06:41 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 18:06:41 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 18:06:41 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 18:06:41 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 18:06:41 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 18:06:41 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 18:06:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:06:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:06:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:06:43 INFO - Installing collected packages: mozsystemmonitor 18:06:43 INFO - Running setup.py install for mozsystemmonitor 18:06:43 INFO - Successfully installed mozsystemmonitor 18:06:43 INFO - Cleaning up... 18:06:43 INFO - Return code: 0 18:06:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:43 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:43 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 18:06:43 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 18:06:43 INFO - Using env: {'DISPLAY': ':0', 18:06:43 INFO - 'HOME': '/home/cltbld', 18:06:43 INFO - 'LANG': 'en_US.UTF-8', 18:06:43 INFO - 'LANGUAGE': 'en_US:en', 18:06:43 INFO - 'LOGNAME': 'cltbld', 18:06:43 INFO - 'MAIL': '/var/mail/cltbld', 18:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:43 INFO - 'MOZ_NO_REMOTE': '1', 18:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:43 INFO - 'NO_EM_RESTART': '1', 18:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:43 INFO - 'PWD': '/builds/slave/test', 18:06:43 INFO - 'SHELL': '/bin/bash', 18:06:43 INFO - 'SHLVL': '1', 18:06:43 INFO - 'TERM': 'linux', 18:06:43 INFO - 'TMOUT': '86400', 18:06:43 INFO - 'USER': 'cltbld', 18:06:43 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:43 INFO - '_': '/tools/buildbot/bin/python'} 18:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:06:43 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 18:06:43 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 18:06:43 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 18:06:43 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 18:06:43 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 18:06:43 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 18:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:06:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:06:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:45 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 18:06:46 INFO - Downloading docopt-0.6.1.tar.gz 18:06:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:06:46 INFO - Running setup.py install for blobuploader 18:06:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:06:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:06:46 INFO - Running setup.py install for requests 18:06:46 INFO - Running setup.py install for docopt 18:06:46 INFO - Successfully installed blobuploader requests docopt 18:06:46 INFO - Cleaning up... 18:06:46 INFO - Return code: 0 18:06:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:46 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:46 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 18:06:46 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 18:06:46 INFO - Using env: {'DISPLAY': ':0', 18:06:46 INFO - 'HOME': '/home/cltbld', 18:06:46 INFO - 'LANG': 'en_US.UTF-8', 18:06:46 INFO - 'LANGUAGE': 'en_US:en', 18:06:46 INFO - 'LOGNAME': 'cltbld', 18:06:46 INFO - 'MAIL': '/var/mail/cltbld', 18:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:46 INFO - 'MOZ_NO_REMOTE': '1', 18:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:46 INFO - 'NO_EM_RESTART': '1', 18:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:46 INFO - 'PWD': '/builds/slave/test', 18:06:46 INFO - 'SHELL': '/bin/bash', 18:06:46 INFO - 'SHLVL': '1', 18:06:46 INFO - 'TERM': 'linux', 18:06:46 INFO - 'TMOUT': '86400', 18:06:46 INFO - 'USER': 'cltbld', 18:06:46 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:46 INFO - '_': '/tools/buildbot/bin/python'} 18:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:06:47 INFO - Running setup.py (path:/tmp/pip-N3fjMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:06:47 INFO - Running setup.py (path:/tmp/pip-L4lHVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:06:47 INFO - Running setup.py (path:/tmp/pip-ysfsd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:06:47 INFO - Running setup.py (path:/tmp/pip-d_zZ3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:06:47 INFO - Running setup.py (path:/tmp/pip-NLIBoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:06:47 INFO - Running setup.py (path:/tmp/pip-Bvj_zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:06:47 INFO - Running setup.py (path:/tmp/pip-T56Uzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:06:47 INFO - Running setup.py (path:/tmp/pip-90yXaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:06:47 INFO - Running setup.py (path:/tmp/pip-xbV8OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:06:47 INFO - Running setup.py (path:/tmp/pip-bckEIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:06:47 INFO - Running setup.py (path:/tmp/pip-gM_QVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:06:47 INFO - Running setup.py (path:/tmp/pip-JLhr7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:06:47 INFO - Running setup.py (path:/tmp/pip-a6aRQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:06:47 INFO - Running setup.py (path:/tmp/pip-4pmY8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:48 INFO - Running setup.py (path:/tmp/pip-BzY4Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:06:48 INFO - Running setup.py (path:/tmp/pip-MIsjPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:06:48 INFO - Running setup.py (path:/tmp/pip-9rLBuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:06:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:06:48 INFO - Running setup.py install for manifestparser 18:06:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:06:48 INFO - Running setup.py install for mozcrash 18:06:48 INFO - Running setup.py install for mozdebug 18:06:48 INFO - Running setup.py install for mozdevice 18:06:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:06:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:06:48 INFO - Running setup.py install for mozfile 18:06:48 INFO - Running setup.py install for mozhttpd 18:06:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:06:48 INFO - Running setup.py install for mozinfo 18:06:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:06:48 INFO - Running setup.py install for mozInstall 18:06:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Running setup.py install for mozleak 18:06:49 INFO - Running setup.py install for mozlog 18:06:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Running setup.py install for moznetwork 18:06:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Running setup.py install for mozprocess 18:06:49 INFO - Running setup.py install for mozprofile 18:06:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Running setup.py install for mozrunner 18:06:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:06:49 INFO - Running setup.py install for mozscreenshot 18:06:49 INFO - Running setup.py install for moztest 18:06:49 INFO - Running setup.py install for mozversion 18:06:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:06:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:06:50 INFO - Cleaning up... 18:06:50 INFO - Return code: 0 18:06:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06: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')]} 18:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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')]} 18:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06: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', '-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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:50 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 18:06:50 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 18:06:50 INFO - Using env: {'DISPLAY': ':0', 18:06:50 INFO - 'HOME': '/home/cltbld', 18:06:50 INFO - 'LANG': 'en_US.UTF-8', 18:06:50 INFO - 'LANGUAGE': 'en_US:en', 18:06:50 INFO - 'LOGNAME': 'cltbld', 18:06:50 INFO - 'MAIL': '/var/mail/cltbld', 18:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:50 INFO - 'MOZ_NO_REMOTE': '1', 18:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:50 INFO - 'NO_EM_RESTART': '1', 18:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:50 INFO - 'PWD': '/builds/slave/test', 18:06:50 INFO - 'SHELL': '/bin/bash', 18:06:50 INFO - 'SHLVL': '1', 18:06:50 INFO - 'TERM': 'linux', 18:06:50 INFO - 'TMOUT': '86400', 18:06:50 INFO - 'USER': 'cltbld', 18:06:50 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:50 INFO - '_': '/tools/buildbot/bin/python'} 18:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:06:50 INFO - Running setup.py (path:/tmp/pip-Rc_1DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:06:50 INFO - Running setup.py (path:/tmp/pip-3wVOG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:06:50 INFO - Running setup.py (path:/tmp/pip-88ZRwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:06:50 INFO - Running setup.py (path:/tmp/pip-3x1lC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:06:50 INFO - Running setup.py (path:/tmp/pip-JWCDUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:06:50 INFO - Running setup.py (path:/tmp/pip-F4qlVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:06:50 INFO - Running setup.py (path:/tmp/pip-xHvfdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:06:50 INFO - Running setup.py (path:/tmp/pip-k4Vxup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:06:50 INFO - Running setup.py (path:/tmp/pip-olQIZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:06:50 INFO - Running setup.py (path:/tmp/pip-cfQv0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:06:50 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)) 18:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:06:50 INFO - Running setup.py (path:/tmp/pip-GxIOxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:06:51 INFO - Running setup.py (path:/tmp/pip-kG1iks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:06:51 INFO - Running setup.py (path:/tmp/pip-UNF3w8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:06:51 INFO - Running setup.py (path:/tmp/pip-yVrZas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:51 INFO - Running setup.py (path:/tmp/pip-goHD7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:06:51 INFO - Running setup.py (path:/tmp/pip-EucMp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:06:51 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)) 18:06:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:06:51 INFO - Running setup.py (path:/tmp/pip-LWug7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:06:51 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)) 18:06:51 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)) 18:06:51 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)) 18:06:51 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)) 18:06:51 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)) 18:06:51 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)) 18:06:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:06:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:06:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:53 INFO - Downloading blessings-1.5.1.tar.gz 18:06:53 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 18:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:06:53 INFO - Installing collected packages: blessings 18:06:53 INFO - Running setup.py install for blessings 18:06:53 INFO - Successfully installed blessings 18:06:53 INFO - Cleaning up... 18:06:53 INFO - Return code: 0 18:06:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:06:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:53 INFO - Reading from file tmpfile_stdout 18:06:53 INFO - Current package versions: 18:06:53 INFO - argparse == 1.2.1 18:06:53 INFO - blessings == 1.5.1 18:06:53 INFO - blobuploader == 1.2.4 18:06:53 INFO - docopt == 0.6.1 18:06:53 INFO - manifestparser == 1.1 18:06:53 INFO - mozInstall == 1.12 18:06:53 INFO - mozcrash == 0.16 18:06:53 INFO - mozdebug == 0.1 18:06:53 INFO - mozdevice == 0.47 18:06:53 INFO - mozfile == 1.2 18:06:53 INFO - mozhttpd == 0.7 18:06:53 INFO - mozinfo == 0.9 18:06:53 INFO - mozleak == 0.1 18:06:53 INFO - mozlog == 3.1 18:06:53 INFO - moznetwork == 0.27 18:06:53 INFO - mozprocess == 0.22 18:06:53 INFO - mozprofile == 0.27 18:06:53 INFO - mozrunner == 6.11 18:06:53 INFO - mozscreenshot == 0.1 18:06:53 INFO - mozsystemmonitor == 0.0 18:06:53 INFO - moztest == 0.7 18:06:53 INFO - mozversion == 1.4 18:06:53 INFO - psutil == 3.1.1 18:06:53 INFO - requests == 1.2.3 18:06:53 INFO - wsgiref == 0.1.2 18:06:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:53 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:53 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 18:06:53 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 18:06:53 INFO - Using env: {'DISPLAY': ':0', 18:06:53 INFO - 'HOME': '/home/cltbld', 18:06:53 INFO - 'LANG': 'en_US.UTF-8', 18:06:53 INFO - 'LANGUAGE': 'en_US:en', 18:06:53 INFO - 'LOGNAME': 'cltbld', 18:06:53 INFO - 'MAIL': '/var/mail/cltbld', 18:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:53 INFO - 'MOZ_NO_REMOTE': '1', 18:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:53 INFO - 'NO_EM_RESTART': '1', 18:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:53 INFO - 'PWD': '/builds/slave/test', 18:06:53 INFO - 'SHELL': '/bin/bash', 18:06:53 INFO - 'SHLVL': '1', 18:06:53 INFO - 'TERM': 'linux', 18:06:53 INFO - 'TMOUT': '86400', 18:06:53 INFO - 'USER': 'cltbld', 18:06:53 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:53 INFO - '_': '/tools/buildbot/bin/python'} 18:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 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)) 18:06:53 INFO - Cleaning up... 18:06:53 INFO - Return code: 0 18:06:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:53 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 0x1544030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1775210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, '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': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', '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 18:06:53 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 18:06:53 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 18:06:53 INFO - Using env: {'DISPLAY': ':0', 18:06:53 INFO - 'HOME': '/home/cltbld', 18:06:53 INFO - 'LANG': 'en_US.UTF-8', 18:06:53 INFO - 'LANGUAGE': 'en_US:en', 18:06:53 INFO - 'LOGNAME': 'cltbld', 18:06:53 INFO - 'MAIL': '/var/mail/cltbld', 18:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:53 INFO - 'MOZ_NO_REMOTE': '1', 18:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:06:53 INFO - 'NO_EM_RESTART': '1', 18:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:53 INFO - 'PWD': '/builds/slave/test', 18:06:53 INFO - 'SHELL': '/bin/bash', 18:06:53 INFO - 'SHLVL': '1', 18:06:53 INFO - 'TERM': 'linux', 18:06:53 INFO - 'TMOUT': '86400', 18:06:53 INFO - 'USER': 'cltbld', 18:06:53 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:53 INFO - '_': '/tools/buildbot/bin/python'} 18:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:54 INFO - Downloading/unpacking jsonschema 18:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:06:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:06:55 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 18:06:55 INFO - Downloading/unpacking functools32 (from jsonschema) 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06:55 INFO - Downloading functools32-3.2.3-2.tar.gz 18:06:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:06:55 INFO - warning: no files found matching '*.txt' 18:06:55 INFO - no previously-included directories found matching 'build' 18:06:55 INFO - no previously-included directories found matching 'dist' 18:06:55 INFO - no previously-included directories found matching '.git*' 18:06:55 INFO - Installing collected packages: jsonschema, functools32 18:06:55 INFO - Running setup.py install for functools32 18:06:56 INFO - warning: no files found matching '*.txt' 18:06:56 INFO - no previously-included directories found matching 'build' 18:06:56 INFO - no previously-included directories found matching 'dist' 18:06:56 INFO - no previously-included directories found matching '.git*' 18:06:56 INFO - Successfully installed jsonschema functools32 18:06:56 INFO - Cleaning up... 18:06:56 INFO - Return code: 0 18:06:56 INFO - Running post-action listener: _resource_record_post_action 18:06:56 INFO - Running post-action listener: _start_resource_monitoring 18:06:56 INFO - Starting resource monitoring. 18:06:56 INFO - ##### 18:06:56 INFO - ##### Running install step. 18:06:56 INFO - ##### 18:06:56 INFO - Running pre-action listener: _resource_record_pre_action 18:06:56 INFO - Running main action method: install 18:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:56 INFO - Reading from file tmpfile_stdout 18:06:56 INFO - Detecting whether we're running mozinstall >=1.0... 18:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:06:56 INFO - Reading from file tmpfile_stdout 18:06:56 INFO - Output received: 18:06:56 INFO - Usage: mozinstall [options] installer 18:06:56 INFO - Options: 18:06:56 INFO - -h, --help show this help message and exit 18:06:56 INFO - -d DEST, --destination=DEST 18:06:56 INFO - Directory to install application into. [default: 18:06:56 INFO - "/builds/slave/test"] 18:06:56 INFO - --app=APP Application being installed. [default: firefox] 18:06:56 INFO - mkdir: /builds/slave/test/build/application 18:06:56 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'] 18:06:56 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 18:07:10 INFO - Reading from file tmpfile_stdout 18:07:10 INFO - Output received: 18:07:10 INFO - /builds/slave/test/build/application/firefox/firefox 18:07:10 INFO - Running post-action listener: _resource_record_post_action 18:07:10 INFO - ##### 18:07:10 INFO - ##### Running run-tests step. 18:07:10 INFO - ##### 18:07:10 INFO - Running pre-action listener: _resource_record_pre_action 18:07:10 INFO - Running main action method: run_tests 18:07:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:07:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:07:10 INFO - Python 2.7.3 18:07:10 INFO - Return code: 0 18:07:10 INFO - grabbing minidump binary from tooltool 18:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:07:10 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 0x1807220>, '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 0x17732b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1773740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:07:10 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 18:07:10 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 18:07:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:07:10 INFO - Return code: 0 18:07:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:07:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:07:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:07:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:07:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:07:10 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] in /builds/slave/test/build 18:07:10 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost 18:07:10 INFO - Using env: {'DISPLAY': ':0', 18:07:10 INFO - 'HOME': '/home/cltbld', 18:07:10 INFO - 'LANG': 'en_US.UTF-8', 18:07:10 INFO - 'LANGUAGE': 'en_US:en', 18:07:10 INFO - 'LOGNAME': 'cltbld', 18:07:10 INFO - 'MAIL': '/var/mail/cltbld', 18:07:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:07:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:07:10 INFO - 'MOZ_NO_REMOTE': '1', 18:07:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:07:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:07:10 INFO - 'NO_EM_RESTART': '1', 18:07:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:07:10 INFO - 'PWD': '/builds/slave/test', 18:07:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:07:10 INFO - 'SHELL': '/bin/bash', 18:07:10 INFO - 'SHLVL': '1', 18:07:10 INFO - 'TERM': 'linux', 18:07:10 INFO - 'TMOUT': '86400', 18:07:10 INFO - 'USER': 'cltbld', 18:07:10 INFO - 'XDG_SESSION_COOKIE': '7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076', 18:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:07:10 INFO - '_': '/tools/buildbot/bin/python'} 18:07:10 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost'] with output_timeout 3600 18:07:11 INFO - mozversion INFO | application_buildid: 20151124170931 18:07:11 INFO - mozversion INFO | application_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 18:07:11 INFO - mozversion INFO | application_display_name: Nightly 18:07:11 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:07:11 INFO - mozversion INFO | application_name: Firefox 18:07:11 INFO - mozversion INFO | application_remotingname: firefox 18:07:11 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:07:11 INFO - mozversion INFO | application_vendor: Mozilla 18:07:11 INFO - mozversion INFO | application_version: 45.0a1 18:07:11 INFO - mozversion INFO | platform_buildid: 20151124170931 18:07:11 INFO - mozversion INFO | platform_changeset: f39db2865764809d45ea45f70e1ea0d56bdcc082 18:07:11 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:07:11 INFO - mozversion INFO | platform_version: 45.0a1 18:07:11 INFO - 2015-11-24 18:07:11,071 DEBUG : using testdate: 1448417231 18:07:11 INFO - 2015-11-24 18:07:11,071 DEBUG : actual date: 1448417231 18:07:11 INFO - 2015-11-24 18:07:11,079 INFO : starting webserver on 'localhost:56357' 18:07:11 INFO - 2015-11-24 18:07:11,080 INFO : Starting test suite talos-linux64-ix-008 18:07:11 INFO - 2015-11-24 18:07:11,080 INFO : Starting test dromaeo_css 18:07:11 INFO - 2015-11-24 18:07:11,080 DEBUG : operating with platform_type : linux_ 18:07:11 INFO - 2015-11-24 18:07:11,081 INFO : Initialising browser for dromaeo_css test... 18:07:11 INFO - 2015-11-24 18:07:11,092 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8ER1S9/profile http://localhost:56357/getInfo.html 18:07:15 INFO - 2015-11-24 18:07:15,177 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:07:15 INFO - 2015-11-24 18:07:15,177 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:07:15 INFO - 2015-11-24 18:07:15,177 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:07:15 INFO - 2015-11-24 18:07:15,177 DEBUG : BROWSER_OUTPUT: __metrics 18:07:15 INFO - 2015-11-24 18:07:15,334 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:07:16 INFO - 2015-11-24 18:07:16,511 INFO : Browser initialized. 18:07:16 INFO - 2015-11-24 18:07:16,512 INFO : Running cycle 1/1 for dromaeo_css test... 18:07:16 INFO - 2015-11-24 18:07:16,512 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8ER1S9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:07:28 INFO - 2015-11-24 18:07:28,403 DEBUG : BROWSER_OUTPUT: RSS: Main: 138747904 18:07:28 INFO - 2015-11-24 18:07:28,404 DEBUG : BROWSER_OUTPUT: 18:08:19 INFO - 2015-11-24 18:08:19,578 DEBUG : BROWSER_OUTPUT: 1448417299576 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] 18:10:30 INFO - 2015-11-24 18:10:30,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56357/tests/dromaeo/cssquery-ext.html) 18:10:32 INFO - 2015-11-24 18:10:32,479 DEBUG : BROWSER_OUTPUT: RSS: Main: 289169408 18:10:32 INFO - 2015-11-24 18:10:32,479 DEBUG : BROWSER_OUTPUT: 18:13:35 INFO - 2015-11-24 18:13:35,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-ext.html (next: http://localhost:56357/tests/dromaeo/cssquery-jquery.html) 18:13:35 INFO - 2015-11-24 18:13:35,711 DEBUG : BROWSER_OUTPUT: RSS: Main: 225771520 18:13:35 INFO - 2015-11-24 18:13:35,712 DEBUG : BROWSER_OUTPUT: 18:16:37 INFO - 2015-11-24 18:16:37,716 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56357/tests/dromaeo/cssquery-mootools.html) 18:16:38 INFO - 2015-11-24 18:16:38,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 215990272 18:16:38 INFO - 2015-11-24 18:16:38,296 DEBUG : BROWSER_OUTPUT: 18:19:40 INFO - 2015-11-24 18:19:40,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56357/tests/dromaeo/cssquery-prototype.html) 18:19:40 INFO - 2015-11-24 18:19:40,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 204357632 18:19:40 INFO - 2015-11-24 18:19:40,775 DEBUG : BROWSER_OUTPUT: 18:22:43 INFO - 2015-11-24 18:22:43,528 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56357/tests/dromaeo/cssquery-yui.html) 18:22:44 INFO - 2015-11-24 18:22:44,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 209395712 18:22:44 INFO - 2015-11-24 18:22:44,122 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/dromaeo/cssquery-yui.html (next: http://localhost:56357/tests/dromaeo/cssquery-dojo.html) 18:25:46 INFO - 2015-11-24 18:25:46,783 DEBUG : BROWSER_OUTPUT: RSS: Main: 221544448 18:25:46 INFO - 2015-11-24 18:25:46,783 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,783 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:25:46 INFO - 2015-11-24 18:25:46,784 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:25:46 INFO - 2015-11-24 18:25:46,784 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:25:46 INFO - 2015-11-24 18:25:46,784 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:25:46 INFO - 2015-11-24 18:25:46,784 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2586.0173134209;2672.933176670582;2678.076063758005;2679.7320267973205;2701.500222458845;4373.518912919576;4501.977490112553;4574.83988060418;4594.58648721615;4641.512641172675;4459.799309031093;4545.11370282795;4554.863354099374;4577.130345234403;4659.021605462848;4289.485261768592;4297.205017071836;4298.204832106055;4322.913541729164;4352.455943007124;4341.78291085446;4406.2289099407635;4410.140022695576;4414.690971631987;4438.312061630436;4083.4283200351974;4169.666426685888;4179.561146079648;4259.424977628011;4280.614744672963;4302.032042790347;4305.763183024932;4314.590113939171;4339.1104823511105;4351.49957754859;4094.5905409459115;4288.356746488021;4289.24938135825;4304.827806887721;4340.370646256275;4462.44219472566;4471.418715566988;4534;4583.289590113504;4600.309953506967;4884.047610715912;4896.975515122402;4905.754712264373;4906.877328066791;4953.20748680213;4729.929051064237;4917.213245880677;4929.926051109236;4937.753112344404;4971.875703107415;4449.2213862574245;4457.331400289982;4502.4371953505815;4591;4600.907981840344;5065.062963351792;5070.062038522885;5071.8985620287385;5074.11203039474;5075.492450754969;4860.052289803525;4860.0765854488245;4865.56209941107;4865.8053677853195;4909.656324057281;4732.692374995614;4756.6670333077045;4835.274708793639;4843.515648435128;4994.051130285234;4812.639052071108;4812.687175333592;4815.518448155227;4820.108279968217;4846.079244943449;4814.061258054646;4819.759012049383;4820.807167713322;4823.831165909176;4862.294967229803;3955.4462375267485;4025.0339918419368;4049.3318602430268;4053.43251944728;4072.124493233969;3527.1358517163458;3571.428571428559;3579.910502237413;3601.5678118625933;3608.8376023079027;4230;4274.829006839692;4277.272863613193;4310.827566897352;4323.589259020388;4724;4882.12121818069;4883.34074899891;4967.230079337708;4969.204927211629;4155.709100363006;4214.936775948302;4217.894552636148;4255.510616279167;4257;4367.235733746582;4388.166248412791;4390.978045109754;4397.296432570837;4429.75636339998;4446.911061778811;4550.453945526558;4553.499115097403;4706.247000479907;4753.453352864396;4852.75736213195;4899.191633380595;4915.975420122876;4917.827876024322;4958.330625365529;4718.174319494144;4725.054989002145;4725.669203155883;4728.739919304471;4747.026859493864;4632.976835115803;4743.66794324394;4748.881277968078;4916;4956.479569645136;4872.634552408513;4872.829450969199;4886.633502487257;4894.412670479565;4895.118878601886;4679.9064018718755;4690.179218636793;4727.290906364073;4738.739369334719;4790.7365094920115;4788.401449818773;4789.3294938709305;4790.8323208687525;4826.565609095198;4841.927371089366;4702.247640377524;4705.34125222476;4705.45086882817;4710.670253082251;4743.881402964954;4622.422197225347;4681.063787242497;4732.2428411454;4735.33705281254;4821.397325334333;4750.453697824656;4755.096531658974;4764.9047019058735;4765.046990601824;4796.304535842353 18:25:46 INFO - 2015-11-24 18:25:46,785 DEBUG : BROWSER_OUTPUT: |1;ext.html;560.3360018378221;562.3252097483021;567.3560508822485;569.4391024840531;576.6050255574931;25450.363740906465;34944;35612.10969725758;35663.643363566356;35678.643213567855;40157.59842401575;40816.59183408165;40895.59104408955;40895.795521022425;40925.59074409255;6915.965420172892;7027.191872934627;7904.565248911307;8077.555734434603;8082.676692932305;9916.355436896614;10135.847962280572;10151.441670708107;10153.187744980383;10153.390796552194;10118.2411319151;10242.385456872575;10268.486575671224;10274.332168409066;10278.897211027906;6783.050372947767;6906.827329316757;6919.135108111486;6949.270326615683;10119.089281964621;6832.6925288362145;9943.950280248553;10383.79232415355;10386.324888882198;10388.168946484264;37058.44412333817;37611.24777504462;37612.24775504502;37628.43557346642;37640;2812.5640525718545;2850.5011622966103;2903.3612605226817;2933.6919623439444;3351.312980838922;28409.28976775597;28664.856675716695;28811.135665930055;29046.12861614155;29069.70930290691;3362.714169295613;3530.5233793437815;3533.4169861972746;3544.379733546619;3663.9450408243897;220631;224275;224380;224625;224831;49299.50700493021;55469.1679624806;55841.72079139578;55848.72075639596;55895.72052139713;46520.7673961628;60121.398786012454;62104.37895621076;62111.6894415525;62195.6890215546;13084;13279.66800829987;13360.198388096745;13360.799588006188;13385;9949.452780097165;9992.300538962203;9997.900020999698;10001.499925003865;10004.099631033241;18626.81373186251;19313.22747090129;19356.32252871143;19523.8047619522;19549.706754398696;7544.245575442412;7617.885731714029;7652.579108149105;7742.729004484816;7851.842963140705;831.62992468351;848.5672307123289;897.8024834536392;898.0570401078841;976.8632391465201;26346.604800928002;26449.338766530607;26826.19521414361;27032;27050.864745676146;957.9988911587408;984.9261305401981;985.8915519292872;1014.6347314966606;1106.5241945963153;13940.721185576434;14108.198817324457;14140;14168.716625667428;14329.713405732036;260668;262869;263689;264215;264227;44936;45372.009079001284;45774.5422545777;45817.312740308924;45845.31232031455;1178.7328621731672;1199.854502693193;1208.2629595946466;1210.2133613151345;1211.1703483114095;25300.493990119725;26478.205653830417;26735.331616709736;26957.73042269552;26965.595516067668;40387.1922561553;40715.38926916039;40894;40907.38638920359;40922.1815563693;249253;251169;252511;253094;253609;24228.273151805475;24919.87540062288;28914.42171156523;29683.55474667925;29973.250668733457;34133.48799768055;34357.656423435445;34563.48154777731;34879.47680784739;35053.64946350606;30359;30367.544486832274;32654.51018234776;33104.50343244901;33200.16999574933;25247.368815779017;30225.546616801206;32086.35827283488;33760.324793504486;34405.311893761485;30256.697433026267;30773.23066923345;30913.8454307727;30944.845275773478;30974.690253097182;26280.8685956569;27180.864095679393;27219;27260.454790903674;27395.452090958468;23228.883855580614;23575.764242357356;23697.52604947926;23844;23891.283261502183 18:25:46 INFO - 2015-11-24 18:25:46,786 DEBUG : BROWSER_OUTPUT: |2;jquery.html;335.2640953107929;336.49693707906675;338.94407422775225;340.180165800421;340.29898409276876;12061.819072713894;12257.632271031884;14684.19236941969;16061.919690401546;16713.74929376059;14552.563423097297;14864.182469964147;14955.252237388142;15325.540233792975;15329.693406131899;3722.162513434469;4876.707397556149;4963.652544321899;4974.228994505857;5050.090983622947;4967.751612419383;5004.84985450437;5013.172826483626;5016.372953380827;5021.297018417425;4882.926756098661;4927.531884470969;4946.084974279752;4976.4525902150735;4976.676516026447;4902.5342592453835;4941.777620007072;4948.703077815325;4949.975250123763;4950.282209079699;4431;4431.800568974404;4439.2675208590545;4439.955600443986;4813.879653008667;11601.129915256304;11733.765324693459;11740.060795136369;11773.823392649118;15801.288941997638;1800.0639667372893;1818.7544681467964;1833.62410705805;1842.9815701842945;1849.056980939717;9127.087291270886;9217.216536594398;9342.486163261017;9355;11708.12189085822;1707.214681246628;1750.5711100780263;1797.0834874214115;1806.66576683314;1848.9722654160198;51691.48308516904;53376.46623533754;54155.45844541543;54847;54943.725281373736;9724.465154416434;9791.559379827924;9812.215022798302;9825.066618671357;9832;10420.270581059252;10761.56953721858;11310.377929213972;12458.190217635825;12492.50029998808;5475.6440831345835;5578.442155784389;5991.7902873399225;6828.283030281749;6884.586924784428;6439.5170362223025;6460.612363258219;6475.125858009203;6602.1747281589805;6614.536982411281;4046.6560342371226;4054.0675644601906;4054.736442131252;4079.0414252650603;4109.404136400265;1713.9004719219106;1730.6365663210615;1774.1838754173225;1774.8136445673276;1793.5695433095964;823.4770920465428;863.0290922711949;891.0599317719867;908.0057337215738;988.3822610868208;14956;15521.223938803014;15608.687826243411;15901.443449479213;16198;823.4153750836973;824.9893879996956;840.9950109618946;847.1613103027958;945.7730144765206;7333.706651733977;8346.958265208636;8635.006974197922;8694.608742606599;8979.236764875059;502400;506629;509196;528765;535906;56998.86002280014;58376.708116459144;62341.37658623356;63171;64466.677666612304;453.25213397893737;461.83604820288986;461.9630429565694;462.7339279914063;464.199256282916;9351.625934962798;9431.434113953184;9624.085711857362;9696.5636546357;9911.950440247752;30585.23536911595;31062.534061988637;32469.350612987135;32507.674923250466;32700.182495437806;42780.35829462498;42974.140517190106;43043;43582.16715913455;46522.534774651824;8667.17661822126;8831.072737362741;8916.375853690179;8983.685571004984;8993.100689930954;10520.947395263282;10631;14899.404023838924;15123.39506419775;15764.738820894072;13374.465021399425;13458.730825383633;13550.457981680622;13587.456501740215;13601.523946662215;13122.803157952829;13164.868351316365;13450.529231476852;13614.659633508845;13735;13718;13882.861171388156;13915.791263130859;14350.21073840907;14598.635034124232;8827.337949653673;8981.506017169118;9082.137196966278;9222.815543688954;9224.953875230582;13020.28388438645;13143.474261029452;13339.866601333862;13630.318484075558;13786.586402407944 18:25:46 INFO - 2015-11-24 18:25:46,790 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1374.4502199120357;1551.2631500037483;1624.4720465848598;1628.1289510112092;1662.3932264723371;5582.944170558293;6589.571677840937;6944.02783610295;6946.409555187815;6946.47901407394;6849.4862885283555;7016.4737644676725;7025.824354391143;7032.613206273653;7036.859262814741;2086.405374468277;2114.6722258050027;2119.9364019079453;2121.575684863026;2122.066290832029;2108.304259594338;2125.7555381131215;2135.850490465668;2144.356692992104;2149.2262785397243;2047.508597936492;2051.2410408149008;2057.156565808019;2088.812006919377;2090.1430413530456;2044.5399785048394;2067.5451400691823;2068.927587534429;2069.8240649544805;2071.326818783901;2018.1927229108474;2035.3588619584932;2053.979460205394;2064;2064.3600483850055;5636.859078523029;5802.564807639444;6921;6995.510314278003;6997.650117494156;3956.8615098471705;4052.9189416211807;4100.466939297872;4104.958950410488;4117.300058989949;2753.848538330391;2783.3320003199287;2800.075974928269;2817.5914492398692;2824.7175282471794;905.9275257979345;924.1590152960773;965;970.1559643110469;1643.531593495848;9023.142801433853;9211.124943130391;9213.262938964866;9215.308851836138;9217.216536594398;3691.354013047706;3774.905627359338;3810.7523011004196;3861.2470568238923;3867.0525721197923;7612.429067819936;7614.657340419694;7624.19945905683;7639.732609358646;7640.541567505968;4150.086980864205;4162.62536371728;4165.062860856283;4182.163567286494;4186.58134186585;3826.445165450994;3851.287511810324;3879.689624830007;3882.0683036071705;3888.475055867422;3149.653538110806;3165.4776961801504;3177.0786471922884;3189.936201276008;3194.9361012780078;951.2437612098398;959.2086528613781;959.8320293948531;968.9176420004237;969.5540051576354;801.9599020048975;818.7707441916273;818.8444195602935;819.512390127873;912.1881525442361;6437.871242575122;6446.935530644727;6476.255230648441;6476.902846457307;6515.120458738104;429.23595999121613;433.59892099808184;440.50883265159115;442.44031300405015;448.8272015274143;4312.504062032844;5080.923786143211;5133.307003554547;5151.8196863109615;5154.072266991902;6954.478414118961;7768.2231776823;7924.683012679544;7953;7960.840783184304;7830.647620857075;7884.448088633855;7898.091719452222;7899;7920.24757648034;6177.876442471125;6444.645544495004;6486.67566621667;6497.18785151856;6509.44669703071;6150.846228844314;6168.537359697951;6179.258488981351;6200.875982480235;6206.751729930933;7228.674709638184;7389.778306650809;7415.851682966419;7420.851582968418;7424.406047516078;7326.267373262631;7436.665350059152;7441.590712510864;7458.5151965122095;7616.581088040211;5975.073863551156;5982.820515384546;6060.393960603904;6061.515078793598;6063.333033366414;6393.744250230125;6468.708908099241;6470.48236141117;6471.708773105316;6498.415142637185;5951.791687290924;6000.729967151576;6027.36712645166;6117.357677443804;6131.448169664752;5794.159846822272;5928.110783382527;5975.521958243417;5980.760769569342;6062.454379105725;6080.543959202989;6119.755209791559;6125.693715314305;6195.473384762346;6256.5307601930945;5580.637258578179;5616.129499927518;5617.9719101404235;5623.606347555632;5625;5808.157817116579;5822.3304320003745;5856.736446859816;5861.648301101947;5868 18:25:46 INFO - 2015-11-24 18:25:46,790 DEBUG : BROWSER_OUTPUT: |4;prototype.html;300.96388433387995;309.11746962422285;310.10070794695366;311.93137509747856;315.9921001974952;5128.102582048136;5128.948710512894;5245.816396426126;5248.13405788045;5348.839534813957;5082.771275292612;5199.662021968569;5212.061828870802;5215.86960325992;5224.164133738603;3848.8460461581644;4088.9182216355657;4093.4678491796176;4093.713440059197;4094.7133700640966;3910.511186101737;3972.880813575583;4085.9387109193385;4088.7342322748927;4091.7954102294916;4023.6177563131455;4040.4747382840337;4040.7777572233517;4046.2514434829504;4049.5545489996075;4041.1715598302426;4049.898752531181;4051.4125451809323;4053.6757059435467;4061.411095391152;3547.3260080584605;3549.5208146900095;3552.573691157052;3554.1470047188745;3560.145565064366;4945;5046.369203849519;5058.3929928408825;5069.391672999227;5075.162598171298;4615.746133962631;4616.376789133457;4622;4623.1678297906365;4623.214053610861;5431.972840135813;5461.18082287656;5467.507924286833;5470.972645136789;5474.91787623186;3572.964270357289;3639.2357604903004;3725.8695945642044;3785.62143785622;3829.885103446901;5673.801416950387;5720.914186287209;5725.427457254283;5735;5737.82786516401;3930.0567863713077;3934.940975885364;3935.7244992850224;3936.996066003208;3940.8177546735865;3973.145773658677;3990.20195960809;3995.680345572347;4002.15954649521;4002.639762421396;4458.331250312479;4574.679772415899;4583.243764778841;4594.931076033862;4603.6777425579885;5479;5487.588430867621;5489.862753431196;5492.5331346835965;5494.670319780826;1400.3768323096328;1418.049906562585;1419.8154239948947;1420.3395421129223;1421.076300404745;954.4654993203828;958.4393130018877;961.3126614470686;977.0669011094387;980.612658000086;4848.151573474628;4859.95140048602;4860.659753817269;4861.149298872683;4864.489228631013;5695.031844586348;5697.914531282034;5701.059325211294;5710.828675139753;5715.142728590745;4851.466338702739;4857.344258525124;4858.319835223072;4858.708477491362;4859.975700121477;2997.415503976704;3057.4955132402906;3077.569140320357;3079.030105516755;3079.076277116856;5375.139977603643;5378.300820893337;5382.865428364322;5385.192221166778;5387.649802762808;5811.302643682785;5812.534997200214;5821.15593238978;5827.562932780059;5830.8542286442325;5316.494932981361;5373.489518495737;5374.5700343972385;5382.192671099367;5383.111786555253;5530.695811730233;5671.688057156896;5675.347335056521;5681.886362272814;5694.088945768823;5386.59600529954;5511.944880551304;5523.972380138073;5545.889082218414;5564.860878478071;5299.178627312618;5332.840014799563;5337.759800808895;5344.171653393731;5402.270693456333;3929.980350098231;3945.861894833667;3945.9408108877815;3947.585503522204;3950.762954222641;5703.971480142739;5715.114157305624;5724.656520608777;5733.254676891977;5734.885302294014;5365.892682146413;5509.338879334506;5533.944660553504;5544.334679838284;5552.361478430033;5516.365617953827;5522.392536820867;5528.91706624409;5532.834014979557;5538.750756215899;5408.864778380572;5423;5424.457554244544;5431.782728690966;5432.049391356418;5437.782488700408;5562.110062389998;5566.916496252478;5579.916301255404;5580.497755202051;5440.401555828935;5446.264754258283;5446.591505637014;5449.5640348772795;5450.236966824568 18:25:46 INFO - 2015-11-24 18:25:46,791 DEBUG : BROWSER_OUTPUT: |5;yui.html;734.823642325842;743.2641684732115;756.882683184106;766.6051983228638;770.9575973321469;11299.774004519904;11505;11508.079353651709;11513.136514761385;11545.191836571434;10193.796124077515;11115.388653624048;11392.316461012346;11425.42872856358;11449.656510304705;752.8494301139766;759.9202083781179;762.4738930138187;766.31797700047;767.6507189228887;754.9396048316149;760.8668483015477;761.5773245848552;761.8971438855739;762.1197516868026;792.6155814429997;802.9357651387875;803.3693550562809;803.5339503088225;803.5660743198665;794.7655441644699;798.309462315099;803.638362736765;803.7950322667682;804.1958041958042;591.1428428778246;593.9079442686391;595.6247564034674;595.7051259626512;596.1981135372916;11724.355160466172;11757.529698812037;11757.588484403004;11878.643640690792;11987.64037078889;11166.385848778315;11590.07279417653;11611.071114310835;11661.6501504955;12086.033117350591;9062.139096785795;9116.954415227949;9121.908780912172;9312.674056408061;9492.715218543455;3201.359728054375;3254.30032543002;3267.5425440438357;3286.063471910518;3348.296857659894;34551.13622159441;34783.30433391318;39325;42214;42894.78552607248;9343.392679475948;9351.859722104175;9391.765205869771;9583.377080489885;9661.855072173783;17034.574135646708;17052.2326495308;17070.743938840926;17115;17121.743173852403;6505.772297969643;6533.836654083686;6568.835779105465;6570.507211959122;6642.634655094016;3633.2006958469096;3662.8351724172476;3682.392405253157;3699.0197597636648;3740.2706472238197;2757.352022274764;2786.5680819473014;2789.804713670024;2791.330080780598;2794;479.11124863378626;482.85755702067974;485.8226747237233;493.24040976895895;501.22560643805406;442.6591524526097;444.7709429643736;455.8267858213857;478.6840685147786;485.75226634416;7403.7408690695565;7474.626268686544;7560.103219121424;7644.197359277307;7808.570528620867;534.1453674121452;545.8171512543263;556.121328301283;567.0841590830861;570.2985328046526;3381.1040074380307;3457.5505184325875;3535.027867336503;3590.174259920233;3661.1945372018113;48962.02075958532;49305.50694493009;49460.75269623629;49541.752291238314;49947.25079123816;42341.78829105835;42974.785126074166;43565.34651980223;43836.780816095714;44073.338899916525;39168.2166356677;39258.803705981285;40522;44969.775151124035;45447.318290225674;7423.554586724814;7618.276263754934;7666.9616651916385;7766.495177813424;7772.339351154989;31014.68985310118;31506.684933150376;31648.841755791073;31898.52152217672;31943.840280798446;35447.291054178255;36384.454233187054;38472.230555388174;39177.216455671296;39524.604753952095;7836.686532538634;8922.643094276156;9153.130452606993;9203.263738900738;9340.906590934004;9935.950320248352;10175.440350780778;10371.325863818827;10411.687649370531;10761.354318740901;10055.19558435338;10187.847182292124;10270.743231419274;10313.690589282334;10478.056974872297;10024.398536087858;10175.694729158136;10234.385936843813;10308.845367319345;10329.328593641389;9742.659006934724;9812.969638188173;9828.4102953823;9933;10167.288289819937;8487.066422693622;8542;8550.57247137653;8601.35489838252;8882.644694212158;7535.849283014419;7928.722494712657;8054.718084867001;8139.633716482891;8255.298299644597 18:25:46 INFO - 2015-11-24 18:25:46,791 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:25:46 INFO - 2015-11-24 18:25:46,791 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:25:46 INFO - 2015-11-24 18:25:46,791 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1506 18:25:46 INFO - 2015-11-24 18:25:46,791 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418346780__endTimestamp 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4532.88 Median:4690.18 stddev:459.12 (9.8%) stddev-sans-first:434.39 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: Values: 2586.0 2672.9 2678.1 2679.7 2701.5 4373.5 4502.0 4574.8 4594.6 4641.5 4459.8 4545.1 4554.9 4577.1 4659.0 4289.5 4297.2 4298.2 4322.9 4352.5 4341.8 4406.2 4410.1 4414.7 4438.3 4083.4 4169.7 4179.6 4259.4 4280.6 4302.0 4305.8 4314.6 4339.1 4351.5 4094.6 4288.4 4289.2 4304.8 4340.4 4462.4 4471.4 4534.0 4583.3 4600.3 4884.0 4897.0 4905.8 4906.9 4953.2 4729.9 4917.2 4929.9 4937.8 4971.9 4449.2 4457.3 4502.4 4591.0 4600.9 5065.1 5070.1 5071.9 5074.1 5075.5 4860.1 4860.1 4865.6 4865.8 4909.7 4732.7 4756.7 4835.3 4843.5 4994.1 4812.6 4812.7 4815.5 4820.1 4846.1 4814.1 4819.8 4820.8 4823.8 4862.3 3955.4 4025.0 4049.3 4053.4 4072.1 3527.1 3571.4 3579.9 3601.6 3608.8 4230.0 4274.8 4277.3 4310.8 4323.6 4724.0 4882.1 4883.3 4967.2 4969.2 4155.7 4214.9 4217.9 4255.5 4257.0 4367.2 4388.2 4391.0 4397.3 4429.8 4446.9 4550.5 4553.5 4706.2 4753.5 4852.8 4899.2 4916.0 4917.8 4958.3 4718.2 4725.1 4725.7 4728.7 4747.0 4633.0 4743.7 4748.9 4916.0 4956.5 4872.6 4872.8 4886.6 4894.4 4895.1 4679.9 4690.2 4727.3 4738.7 4790.7 4788.4 4789.3 4790.8 4826.6 4841.9 4702.2 4705.3 4705.5 4710.7 4743.9 4622.4 4681.1 4732.2 4735.3 4821.4 4750.5 4755.1 4764.9 4765.0 4796.3 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,792 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40507.46 Median:30363.27 stddev:64273.12 (211.7%) stddev-sans-first:64383.43 18:25:46 INFO - 2015-11-24 18:25:46,793 DEBUG : BROWSER_OUTPUT: Values: 560.3 562.3 567.4 569.4 576.6 25450.4 34944.0 35612.1 35663.6 35678.6 40157.6 40816.6 40895.6 40895.8 40925.6 6916.0 7027.2 7904.6 8077.6 8082.7 9916.4 10135.8 10151.4 10153.2 10153.4 10118.2 10242.4 10268.5 10274.3 10278.9 6783.1 6906.8 6919.1 6949.3 10119.1 6832.7 9944.0 10383.8 10386.3 10388.2 37058.4 37611.2 37612.2 37628.4 37640.0 2812.6 2850.5 2903.4 2933.7 3351.3 28409.3 28664.9 28811.1 29046.1 29069.7 3362.7 3530.5 3533.4 3544.4 3663.9 220631.0 224275.0 224380.0 224625.0 224831.0 49299.5 55469.2 55841.7 55848.7 55895.7 46520.8 60121.4 62104.4 62111.7 62195.7 13084.0 13279.7 13360.2 13360.8 13385.0 9949.5 9992.3 9997.9 10001.5 10004.1 18626.8 19313.2 19356.3 19523.8 19549.7 7544.2 7617.9 7652.6 7742.7 7851.8 831.6 848.6 897.8 898.1 976.9 26346.6 26449.3 26826.2 27032.0 27050.9 958.0 984.9 985.9 1014.6 1106.5 13940.7 14108.2 14140.0 14168.7 14329.7 260668.0 262869.0 263689.0 264215.0 264227.0 44936.0 45372.0 45774.5 45817.3 45845.3 1178.7 1199.9 1208.3 1210.2 1211.2 25300.5 26478.2 26735.3 26957.7 26965.6 40387.2 40715.4 40894.0 40907.4 40922.2 249253.0 251169.0 252511.0 253094.0 253609.0 24228.3 24919.9 28914.4 29683.6 29973.3 34133.5 34357.7 34563.5 34879.5 35053.6 30359.0 30367.5 32654.5 33104.5 33200.2 25247.4 30225.5 32086.4 33760.3 34405.3 30256.7 30773.2 30913.8 30944.8 30974.7 26280.9 27180.9 27219.0 27260.5 27395.5 23228.9 23575.8 23697.5 23844.0 23891.3 18:25:46 INFO - 2015-11-24 18:25:46,793 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,793 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26338.28 Median:457.54 stddev:84275.41 (18419.1%) stddev-sans-first:84489.21 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: Values: 335.3 336.5 338.9 340.2 340.3 12061.8 12257.6 14684.2 16061.9 16713.7 14552.6 14864.2 14955.3 15325.5 15329.7 3722.2 4876.7 4963.7 4974.2 5050.1 4967.8 5004.8 5013.2 5016.4 5021.3 4882.9 4927.5 4946.1 4976.5 4976.7 4902.5 4941.8 4948.7 4950.0 4950.3 4431.0 4431.8 4439.3 4440.0 4813.9 11601.1 11733.8 11740.1 11773.8 15801.3 1800.1 1818.8 1833.6 1843.0 1849.1 9127.1 9217.2 9342.5 9355.0 11708.1 1707.2 1750.6 1797.1 1806.7 1849.0 51691.5 53376.5 54155.5 54847.0 54943.7 9724.5 9791.6 9812.2 9825.1 9832.0 10420.3 10761.6 11310.4 12458.2 12492.5 5475.6 5578.4 5991.8 6828.3 6884.6 6439.5 6460.6 6475.1 6602.2 6614.5 4046.7 4054.1 4054.7 4079.0 4109.4 1713.9 1730.6 1774.2 1774.8 1793.6 823.5 863.0 891.1 908.0 988.4 14956.0 15521.2 15608.7 15901.4 16198.0 823.4 825.0 841.0 847.2 945.8 7333.7 8347.0 8635.0 8694.6 8979.2 502400.0 506629.0 509196.0 528765.0 535906.0 56998.9 58376.7 62341.4 63171.0 64466.7 453.3 461.8 462.0 462.7 464.2 9351.6 9431.4 9624.1 9696.6 9912.0 30585.2 31062.5 32469.4 32507.7 32700.2 42780.4 42974.1 43043.0 43582.2 46522.5 8667.2 8831.1 8916.4 8983.7 8993.1 10520.9 10631.0 14899.4 15123.4 15764.7 13374.5 13458.7 13550.5 13587.5 13601.5 13122.8 13164.9 13450.5 13614.7 13735.0 13718.0 13882.9 13915.8 14350.2 14598.6 8827.3 8981.5 9082.1 9222.8 9225.0 13020.3 13143.5 13339.9 13630.3 13786.6 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4739.36 Median:5991.78 stddev:2414.96 (40.3%) stddev-sans-first:2408.50 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: Values: 1374.5 1551.3 1624.5 1628.1 1662.4 5582.9 6589.6 6944.0 6946.4 6946.5 6849.5 7016.5 7025.8 7032.6 7036.9 2086.4 2114.7 2119.9 2121.6 2122.1 2108.3 2125.8 2135.9 2144.4 2149.2 2047.5 2051.2 2057.2 2088.8 2090.1 2044.5 2067.5 2068.9 2069.8 2071.3 2018.2 2035.4 2054.0 2064.0 2064.4 5636.9 5802.6 6921.0 6995.5 6997.7 3956.9 4052.9 4100.5 4105.0 4117.3 2753.8 2783.3 2800.1 2817.6 2824.7 905.9 924.2 965.0 970.2 1643.5 9023.1 9211.1 9213.3 9215.3 9217.2 3691.4 3774.9 3810.8 3861.2 3867.1 7612.4 7614.7 7624.2 7639.7 7640.5 4150.1 4162.6 4165.1 4182.2 4186.6 3826.4 3851.3 3879.7 3882.1 3888.5 3149.7 3165.5 3177.1 3189.9 3194.9 951.2 959.2 959.8 968.9 969.6 802.0 818.8 818.8 819.5 912.2 6437.9 6446.9 6476.3 6476.9 6515.1 429.2 433.6 440.5 442.4 448.8 4312.5 5080.9 5133.3 5151.8 5154.1 6954.5 7768.2 7924.7 7953.0 7960.8 7830.6 7884.4 7898.1 7899.0 7920.2 6177.9 6444.6 6486.7 6497.2 6509.4 6150.8 6168.5 6179.3 6200.9 6206.8 7228.7 7389.8 7415.9 7420.9 7424.4 7326.3 7436.7 7441.6 7458.5 7616.6 5975.1 5982.8 6060.4 6061.5 6063.3 6393.7 6468.7 6470.5 6471.7 6498.4 5951.8 6000.7 6027.4 6117.4 6131.4 5794.2 5928.1 5975.5 5980.8 6062.5 6080.5 6119.8 6125.7 6195.5 6256.5 5580.6 5616.1 5618.0 5623.6 5625.0 5808.2 5822.3 5856.7 5861.6 5868.0 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,794 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4555.71 Median:5220.02 stddev:1336.40 (25.6%) stddev-sans-first:1301.43 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: Values: 301.0 309.1 310.1 311.9 316.0 5128.1 5128.9 5245.8 5248.1 5348.8 5082.8 5199.7 5212.1 5215.9 5224.2 3848.8 4088.9 4093.5 4093.7 4094.7 3910.5 3972.9 4085.9 4088.7 4091.8 4023.6 4040.5 4040.8 4046.3 4049.6 4041.2 4049.9 4051.4 4053.7 4061.4 3547.3 3549.5 3552.6 3554.1 3560.1 4945.0 5046.4 5058.4 5069.4 5075.2 4615.7 4616.4 4622.0 4623.2 4623.2 5432.0 5461.2 5467.5 5471.0 5474.9 3573.0 3639.2 3725.9 3785.6 3829.9 5673.8 5720.9 5725.4 5735.0 5737.8 3930.1 3934.9 3935.7 3937.0 3940.8 3973.1 3990.2 3995.7 4002.2 4002.6 4458.3 4574.7 4583.2 4594.9 4603.7 5479.0 5487.6 5489.9 5492.5 5494.7 1400.4 1418.0 1419.8 1420.3 1421.1 954.5 958.4 961.3 977.1 980.6 4848.2 4860.0 4860.7 4861.1 4864.5 5695.0 5697.9 5701.1 5710.8 5715.1 4851.5 4857.3 4858.3 4858.7 4860.0 2997.4 3057.5 3077.6 3079.0 3079.1 5375.1 5378.3 5382.9 5385.2 5387.6 5811.3 5812.5 5821.2 5827.6 5830.9 5316.5 5373.5 5374.6 5382.2 5383.1 5530.7 5671.7 5675.3 5681.9 5694.1 5386.6 5511.9 5524.0 5545.9 5564.9 5299.2 5332.8 5337.8 5344.2 5402.3 3930.0 3945.9 3945.9 3947.6 3950.8 5704.0 5715.1 5724.7 5733.3 5734.9 5365.9 5509.3 5533.9 5544.3 5552.4 5516.4 5522.4 5528.9 5532.8 5538.8 5408.9 5423.0 5424.5 5431.8 5432.0 5437.8 5562.1 5566.9 5579.9 5580.5 5440.4 5446.3 5446.6 5449.6 5450.2 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11996.03 Median:484.30 stddev:13666.51 (2821.9%) stddev-sans-first:13678.69 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: Values: 734.8 743.3 756.9 766.6 771.0 11299.8 11505.0 11508.1 11513.1 11545.2 10193.8 11115.4 11392.3 11425.4 11449.7 752.8 759.9 762.5 766.3 767.7 754.9 760.9 761.6 761.9 762.1 792.6 802.9 803.4 803.5 803.6 794.8 798.3 803.6 803.8 804.2 591.1 593.9 595.6 595.7 596.2 11724.4 11757.5 11757.6 11878.6 11987.6 11166.4 11590.1 11611.1 11661.7 12086.0 9062.1 9117.0 9121.9 9312.7 9492.7 3201.4 3254.3 3267.5 3286.1 3348.3 34551.1 34783.3 39325.0 42214.0 42894.8 9343.4 9351.9 9391.8 9583.4 9661.9 17034.6 17052.2 17070.7 17115.0 17121.7 6505.8 6533.8 6568.8 6570.5 6642.6 3633.2 3662.8 3682.4 3699.0 3740.3 2757.4 2786.6 2789.8 2791.3 2794.0 479.1 482.9 485.8 493.2 501.2 442.7 444.8 455.8 478.7 485.8 7403.7 7474.6 7560.1 7644.2 7808.6 534.1 545.8 556.1 567.1 570.3 3381.1 3457.6 3535.0 3590.2 3661.2 48962.0 49305.5 49460.8 49541.8 49947.3 42341.8 42974.8 43565.3 43836.8 44073.3 39168.2 39258.8 40522.0 44969.8 45447.3 7423.6 7618.3 7667.0 7766.5 7772.3 31014.7 31506.7 31648.8 31898.5 31943.8 35447.3 36384.5 38472.2 39177.2 39524.6 7836.7 8922.6 9153.1 9203.3 9340.9 9936.0 10175.4 10371.3 10411.7 10761.4 10055.2 10187.8 10270.7 10313.7 10478.1 10024.4 10175.7 10234.4 10308.8 10329.3 9742.7 9813.0 9828.4 9933.0 10167.3 8487.1 8542.0 8550.6 8601.4 8882.6 7535.8 7928.7 8054.7 8139.6 8255.3 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:25:46 INFO - 2015-11-24 18:25:46,796 DEBUG : BROWSER_OUTPUT: 18:25:47 INFO - 2015-11-24 18:25:47,573 INFO : Browser exited with error code: 0 18:25:47 INFO - 2015-11-24 18:25:47,589 INFO : Completed test dromaeo_css (00:18:36) 18:25:47 INFO - 2015-11-24 18:25:47,589 INFO : Starting test kraken 18:25:47 INFO - 2015-11-24 18:25:47,589 DEBUG : operating with platform_type : linux_ 18:25:47 INFO - 2015-11-24 18:25:47,589 INFO : Initialising browser for kraken test... 18:25:47 INFO - 2015-11-24 18:25:47,595 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8HKuBb/profile http://localhost:56357/getInfo.html 18:25:51 INFO - 2015-11-24 18:25:51,574 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:25:51 INFO - 2015-11-24 18:25:51,574 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:25:51 INFO - 2015-11-24 18:25:51,575 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:25:51 INFO - 2015-11-24 18:25:51,575 DEBUG : BROWSER_OUTPUT: __metrics 18:25:51 INFO - 2015-11-24 18:25:51,659 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:53 INFO - 2015-11-24 18:25:53,015 INFO : Browser initialized. 18:25:53 INFO - 2015-11-24 18:25:53,016 INFO : Running cycle 1/1 for kraken test... 18:25:53 INFO - 2015-11-24 18:25:53,016 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8HKuBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:26:04 INFO - 2015-11-24 18:26:04,866 DEBUG : BROWSER_OUTPUT: RSS: Main: 141082624 18:26:04 INFO - 2015-11-24 18:26:04,866 DEBUG : BROWSER_OUTPUT: 18:26:07 INFO - 2015-11-24 18:26:07,463 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56357/tests/kraken/driver.html?testName=audio-beat-detection) 18:26:08 INFO - 2015-11-24 18:26:08,044 DEBUG : BROWSER_OUTPUT: RSS: Main: 176209920 18:26:08 INFO - 2015-11-24 18:26:08,044 DEBUG : BROWSER_OUTPUT: 18:26:10 INFO - 2015-11-24 18:26:10,781 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56357/tests/kraken/driver.html?testName=audio-dft) 18:26:11 INFO - 2015-11-24 18:26:11,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 165978112 18:26:11 INFO - 2015-11-24 18:26:11,363 DEBUG : BROWSER_OUTPUT: 18:26:14 INFO - 2015-11-24 18:26:14,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56357/tests/kraken/driver.html?testName=audio-fft) 18:26:14 INFO - 2015-11-24 18:26:14,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 149622784 18:26:14 INFO - 2015-11-24 18:26:14,947 DEBUG : BROWSER_OUTPUT: 18:26:16 INFO - 2015-11-24 18:26:16,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56357/tests/kraken/driver.html?testName=audio-oscillator) 18:26:17 INFO - 2015-11-24 18:26:17,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 152588288 18:26:17 INFO - 2015-11-24 18:26:17,554 DEBUG : BROWSER_OUTPUT: 18:26:19 INFO - 2015-11-24 18:26:19,792 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56357/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:26:20 INFO - 2015-11-24 18:26:20,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 155545600 18:26:20 INFO - 2015-11-24 18:26:20,358 DEBUG : BROWSER_OUTPUT: 18:26:23 INFO - 2015-11-24 18:26:23,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56357/tests/kraken/driver.html?testName=imaging-darkroom) 18:26:23 INFO - 2015-11-24 18:26:23,922 DEBUG : BROWSER_OUTPUT: RSS: Main: 155230208 18:26:23 INFO - 2015-11-24 18:26:23,922 DEBUG : BROWSER_OUTPUT: 18:26:27 INFO - 2015-11-24 18:26:27,303 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56357/tests/kraken/driver.html?testName=imaging-desaturate) 18:26:27 INFO - 2015-11-24 18:26:27,872 DEBUG : BROWSER_OUTPUT: RSS: Main: 155185152 18:26:27 INFO - 2015-11-24 18:26:27,873 DEBUG : BROWSER_OUTPUT: 18:26:30 INFO - 2015-11-24 18:26:30,682 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56357/tests/kraken/driver.html?testName=json-parse-financial) 18:26:31 INFO - 2015-11-24 18:26:31,263 DEBUG : BROWSER_OUTPUT: RSS: Main: 156454912 18:26:31 INFO - 2015-11-24 18:26:31,264 DEBUG : BROWSER_OUTPUT: 18:26:33 INFO - 2015-11-24 18:26:33,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56357/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:26:33 INFO - 2015-11-24 18:26:33,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 164909056 18:26:33 INFO - 2015-11-24 18:26:33,604 DEBUG : BROWSER_OUTPUT: 18:26:35 INFO - 2015-11-24 18:26:35,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:26:36 INFO - 2015-11-24 18:26:36,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 164241408 18:26:36 INFO - 2015-11-24 18:26:36,075 DEBUG : BROWSER_OUTPUT: 18:26:38 INFO - 2015-11-24 18:26:38,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:26:38 INFO - 2015-11-24 18:26:38,788 DEBUG : BROWSER_OUTPUT: RSS: Main: 173359104 18:26:38 INFO - 2015-11-24 18:26:38,788 DEBUG : BROWSER_OUTPUT: 18:26:41 INFO - 2015-11-24 18:26:41,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:26:41 INFO - 2015-11-24 18:26:41,909 DEBUG : BROWSER_OUTPUT: RSS: Main: 182714368 18:26:41 INFO - 2015-11-24 18:26:41,910 DEBUG : BROWSER_OUTPUT: 18:26:44 INFO - 2015-11-24 18:26:44,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:26:45 INFO - 2015-11-24 18:26:45,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 179331072 18:26:45 INFO - 2015-11-24 18:26:45,097 DEBUG : BROWSER_OUTPUT: 18:26:46 INFO - 2015-11-24 18:26:46,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56357/tests/kraken/driver.html?testName=ai-astar) 18:26:47 INFO - 2015-11-24 18:26:47,107 DEBUG : BROWSER_OUTPUT: RSS: Main: 237899776 18:26:47 INFO - 2015-11-24 18:26:47,108 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,109 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:26:47 INFO - 2015-11-24 18:26:47,110 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;112;109;114;114;115;100;111;110;105 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;137;154;165;131;162;160;129;183;135 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;162;161;160;160;160;158;161;164;162 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;80;95;73;73;72;96;94;72 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;94;140;94;94;95;134;94;94;130 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;109;109;109;109;109;109 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;147;146;147;147;146;146;147 18:26:47 INFO - 2015-11-24 18:26:47,111 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;96;96;95;95 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;71;70;70;71;71;70;70;82;69;67 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;68;69;68;69;69;68;68 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;84;84;83;82;83;82;82;86;91 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;131;127;123;119;121;124;136;124;130 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;150;148;151;148;148;136;149;151;148;136 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;77;69;69;69;69;70;68;70;68;68 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,980 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418407109__endTimestamp 18:26:47 INFO - 2015-11-24 18:26:47,112 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,115 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.10 Median:111.50 stddev:4.58 (4.1%) stddev-sans-first:4.85 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: Values: 111.0 112.0 109.0 114.0 114.0 115.0 100.0 111.0 110.0 105.0 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:151.20 Median:158.00 stddev:17.61 (11.1%) stddev-sans-first:18.59 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: Values: 156.0 137.0 154.0 165.0 131.0 162.0 160.0 129.0 183.0 135.0 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.63 (1.0%) stddev-sans-first:1.69 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: Values: 162.0 162.0 161.0 160.0 160.0 160.0 158.0 161.0 164.0 162.0 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.00 Median:76.50 stddev:10.62 (13.9%) stddev-sans-first:10.87 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 80.0 95.0 73.0 73.0 72.0 96.0 94.0 72.0 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,116 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:94.00 stddev:19.72 (21.0%) stddev-sans-first:20.41 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: Values: 94.0 94.0 140.0 94.0 94.0 95.0 134.0 94.0 94.0 130.0 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 147.0 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 95.0 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:71.10 Median:70.50 stddev:4.01 (5.7%) stddev-sans-first:4.26 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: Values: 71.0 70.0 70.0 71.0 71.0 70.0 70.0 82.0 69.0 67.0 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,117 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 69.0 68.0 69.0 69.0 68.0 68.0 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:2.73 (3.2%) stddev-sans-first:2.89 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: Values: 84.0 84.0 84.0 83.0 82.0 83.0 82.0 82.0 86.0 91.0 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:128.50 stddev:5.23 (4.1%) stddev-sans-first:5.40 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: Values: 130.0 131.0 127.0 123.0 119.0 121.0 124.0 136.0 124.0 130.0 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.50 Median:148.50 stddev:5.66 (3.8%) stddev-sans-first:5.86 18:26:47 INFO - 2015-11-24 18:26:47,118 DEBUG : BROWSER_OUTPUT: Values: 150.0 148.0 151.0 148.0 148.0 136.0 149.0 151.0 148.0 136.0 18:26:47 INFO - 2015-11-24 18:26:47,119 DEBUG : BROWSER_OUTPUT: 18:26:47 INFO - 2015-11-24 18:26:47,119 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.00 stddev:2.67 (3.9%) stddev-sans-first:0.78 18:26:47 INFO - 2015-11-24 18:26:47,119 DEBUG : BROWSER_OUTPUT: Values: 77.0 69.0 69.0 69.0 69.0 70.0 68.0 70.0 68.0 68.0 18:26:47 INFO - 2015-11-24 18:26:47,119 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:26:47 INFO - 2015-11-24 18:26:47,119 DEBUG : BROWSER_OUTPUT: 18:26:48 INFO - 2015-11-24 18:26:48,037 INFO : Browser exited with error code: 0 18:26:48 INFO - 2015-11-24 18:26:48,087 INFO : Completed test kraken (00:01:00) 18:26:48 INFO - 2015-11-24 18:26:48,088 INFO : Starting test v8_7 18:26:48 INFO - 2015-11-24 18:26:48,088 DEBUG : operating with platform_type : linux_ 18:26:48 INFO - 2015-11-24 18:26:48,088 INFO : Initialising browser for v8_7 test... 18:26:48 INFO - 2015-11-24 18:26:48,094 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiQXmPs/profile http://localhost:56357/getInfo.html 18:26:51 INFO - 2015-11-24 18:26:51,894 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:26:51 INFO - 2015-11-24 18:26:51,894 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:26:51 INFO - 2015-11-24 18:26:51,894 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:26:51 INFO - 2015-11-24 18:26:51,894 DEBUG : BROWSER_OUTPUT: __metrics 18:26:52 INFO - 2015-11-24 18:26:52,063 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:26:53 INFO - 2015-11-24 18:26:53,313 INFO : Browser initialized. 18:26:53 INFO - 2015-11-24 18:26:53,314 INFO : Running cycle 1/1 for v8_7 test... 18:26:53 INFO - 2015-11-24 18:26:53,314 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiQXmPs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:27:05 INFO - 2015-11-24 18:27:05,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 139202560 18:27:05 INFO - 2015-11-24 18:27:05,177 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:56357/tests/v8_7/run.html (next: http://localhost:56357/tests/v8_7/run.html) 18:27:26 INFO - 2015-11-24 18:27:26,988 DEBUG : BROWSER_OUTPUT: RSS: Main: 434294784 18:27:26 INFO - 2015-11-24 18:27:26,988 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: |0;Richards;164.52780519907864 18:27:26 INFO - 2015-11-24 18:27:26,990 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;191.3143294432753 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.77777777777777 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1982.1782178217823 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |5;Earley;793.0214115781126 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |6;Boyer;12456.79012345679 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |7;RegExp;26473.684210526317 18:27:26 INFO - 2015-11-24 18:27:26,991 DEBUG : BROWSER_OUTPUT: |8;Splay;598.0861244019138 18:27:26 INFO - 2015-11-24 18:27:26,992 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 18:27:26 INFO - 2015-11-24 18:27:26,992 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:27:26 INFO - 2015-11-24 18:27:26,992 DEBUG : BROWSER_OUTPUT: __startTimestamp1448418446988__endTimestamp 18:27:26 INFO - 2015-11-24 18:27:26,992 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,992 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:27:26 INFO - 2015-11-24 18:27:26,993 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:27:26 INFO - 2015-11-24 18:27:26,993 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,993 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.53 Median:164.53 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,993 DEBUG : BROWSER_OUTPUT: Values: 164.5 18:27:26 INFO - 2015-11-24 18:27:26,993 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:191.31 Median:191.31 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: Values: 191.3 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.78 Median:277.78 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: Values: 277.8 18:27:26 INFO - 2015-11-24 18:27:26,994 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,995 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,996 DEBUG : BROWSER_OUTPUT: Values: 5148.7 18:27:26 INFO - 2015-11-24 18:27:26,996 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,996 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1982.18 Median:1982.18 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,996 DEBUG : BROWSER_OUTPUT: Values: 1982.2 18:27:26 INFO - 2015-11-24 18:27:26,996 DEBUG : BROWSER_OUTPUT: 18:27:26 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:793.02 Median:793.02 stddev:0.00 (0.0%) 18:27:26 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: Values: 793.0 18:27:26 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: 18:27:27 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12456.79 Median:12456.79 stddev:0.00 (0.0%) 18:27:27 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: Values: 12456.8 18:27:27 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: 18:27:27 INFO - 2015-11-24 18:27:26,997 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26473.68 Median:26473.68 stddev:0.00 (0.0%) 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: Values: 26473.7 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:598.09 Median:598.09 stddev:0.00 (0.0%) 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: Values: 598.1 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: Values: 4835.7 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:27:27 INFO - 2015-11-24 18:27:26,998 DEBUG : BROWSER_OUTPUT: 18:27:27 INFO - 2015-11-24 18:27:27,925 INFO : Browser exited with error code: 0 18:27:27 INFO - 2015-11-24 18:27:27,940 INFO : Completed test v8_7 (00:00:39) 18:27:28 INFO - 2015-11-24 18:27:28,358 INFO : Completed test suite (00:20:17) 18:27:28 INFO - 2015-11-24 18:27:28,358 DEBUG : Working with test: dromaeo_css 18:27:28 INFO - 2015-11-24 18:27:28,358 DEBUG : Generating results file: dromaeo_css 18:27:28 INFO - 2015-11-24 18:27:28,359 DEBUG : Working with test: kraken 18:27:28 INFO - 2015-11-24 18:27:28,360 DEBUG : Generating results file: kraken 18:27:28 INFO - 2015-11-24 18:27:28,360 INFO : javascript benchmark 18:27:28 INFO - 2015-11-24 18:27:28,360 DEBUG : Working with test: v8_7 18:27:28 INFO - 2015-11-24 18:27:28,360 DEBUG : Generating results file: v8_7 18:27:28 INFO - 2015-11-24 18:27:28,361 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:27:28 INFO - 2015-11-24 18:27:28,433 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,35]] 18:27:28 INFO - 2015-11-24 18:27:28,433 DEBUG : process_Request line: dromaeo_css 6116.21 graph.html#tests=[[72,132,35]] 18:27:28 INFO - 2015-11-24 18:27:28,433 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:27:28 INFO - 2015-11-24 18:27:28,469 DEBUG : process_Request line: kraken 1525.80 graph.html#tests=[[232,132,35]] 18:27:28 INFO - 2015-11-24 18:27:28,469 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:27:28 INFO - 2015-11-24 18:27:28,511 DEBUG : process_Request line: v8_7 19792.27 graph.html#tests=[[230,132,35]] 18:27:28 INFO - 2015-11-24 18:27:28,516 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2586.0173134209, 2672.933176670582, 2678.076063758005, 2679.7320267973205, 2701.500222458845, 4373.518912919576, 4501.977490112553, 4574.83988060418, 4594.58648721615, 4641.512641172675, 4459.799309031093, 4545.11370282795, 4554.863354099374, 4577.130345234403, 4659.021605462848, 4289.485261768592, 4297.205017071836, 4298.204832106055, 4322.913541729164, 4352.455943007124, 4341.78291085446, 4406.2289099407635, 4410.140022695576, 4414.690971631987, 4438.312061630436, 4083.4283200351974, 4169.666426685888, 4179.561146079648, 4259.424977628011, 4280.614744672963, 4302.032042790347, 4305.763183024932, 4314.590113939171, 4339.1104823511105, 4351.49957754859, 4094.5905409459115, 4288.356746488021, 4289.24938135825, 4304.827806887721, 4340.370646256275, 4462.44219472566, 4471.418715566988, 4534.0, 4583.289590113504, 4600.309953506967, 4884.047610715912, 4896.975515122402, 4905.754712264373, 4906.877328066791, 4953.20748680213, 4729.929051064237, 4917.213245880677, 4929.926051109236, 4937.753112344404, 4971.875703107415, 4449.2213862574245, 4457.331400289982, 4502.4371953505815, 4591.0, 4600.907981840344, 5065.062963351792, 5070.062038522885, 5071.8985620287385, 5074.11203039474, 5075.492450754969, 4860.052289803525, 4860.0765854488245, 4865.56209941107, 4865.8053677853195, 4909.656324057281, 4732.692374995614, 4756.6670333077045, 4835.274708793639, 4843.515648435128, 4994.051130285234, 4812.639052071108, 4812.687175333592, 4815.518448155227, 4820.108279968217, 4846.079244943449, 4814.061258054646, 4819.759012049383, 4820.807167713322, 4823.831165909176, 4862.294967229803, 3955.4462375267485, 4025.0339918419368, 4049.3318602430268, 4053.43251944728, 4072.124493233969, 3527.1358517163458, 3571.428571428559, 3579.910502237413, 3601.5678118625933, 3608.8376023079027, 4230.0, 4274.829006839692, 4277.272863613193, 4310.827566897352, 4323.589259020388, 4724.0, 4882.12121818069, 4883.34074899891, 4967.230079337708, 4969.204927211629, 4155.709100363006, 4214.936775948302, 4217.894552636148, 4255.510616279167, 4257.0, 4367.235733746582, 4388.166248412791, 4390.978045109754, 4397.296432570837, 4429.75636339998, 4446.911061778811, 4550.453945526558, 4553.499115097403, 4706.247000479907, 4753.453352864396, 4852.75736213195, 4899.191633380595, 4915.975420122876, 4917.827876024322, 4958.330625365529, 4718.174319494144, 4725.054989002145, 4725.669203155883, 4728.739919304471, 4747.026859493864, 4632.976835115803, 4743.66794324394, 4748.881277968078, 4916.0, 4956.479569645136, 4872.634552408513, 4872.829450969199, 4886.633502487257, 4894.412670479565, 4895.118878601886, 4679.9064018718755, 4690.179218636793, 4727.290906364073, 4738.739369334719, 4790.7365094920115, 4788.401449818773, 4789.3294938709305, 4790.8323208687525, 4826.565609095198, 4841.927371089366, 4702.247640377524, 4705.34125222476, 4705.45086882817, 4710.670253082251, 4743.881402964954, 4622.422197225347, 4681.063787242497, 4732.2428411454, 4735.33705281254, 4821.397325334333, 4750.453697824656, 4755.096531658974, 4764.9047019058735, 4765.046990601824, 4796.304535842353], "unit": "ms", "name": "dojo.html", "value": 4505.1228717104195}, {"lowerIsBetter": false, "replicates": [560.3360018378221, 562.3252097483021, 567.3560508822485, 569.4391024840531, 576.6050255574931, 25450.363740906465, 34944.0, 35612.10969725758, 35663.643363566356, 35678.643213567855, 40157.59842401575, 40816.59183408165, 40895.59104408955, 40895.795521022425, 40925.59074409255, 6915.965420172892, 7027.191872934627, 7904.565248911307, 8077.555734434603, 8082.676692932305, 9916.355436896614, 10135.847962280572, 10151.441670708107, 10153.187744980383, 10153.390796552194, 10118.2411319151, 10242.385456872575, 10268.486575671224, 10274.332168409066, 10278.897211027906, 6783.050372947767, 6906.827329316757, 6919.135108111486, 6949.270326615683, 10119.089281964621, 6832.6925288362145, 9943.950280248553, 10383.79232415355, 10386.324888882198, 10388.168946484264, 37058.44412333817, 37611.24777504462, 37612.24775504502, 37628.43557346642, 37640.0, 2812.5640525718545, 2850.5011622966103, 2903.3612605226817, 2933.6919623439444, 3351.312980838922, 28409.28976775597, 28664.856675716695, 28811.135665930055, 29046.12861614155, 29069.70930290691, 3362.714169295613, 3530.5233793437815, 3533.4169861972746, 3544.379733546619, 3663.9450408243897, 220631.0, 224275.0, 224380.0, 224625.0, 224831.0, 49299.50700493021, 55469.1679624806, 55841.72079139578, 55848.72075639596, 55895.72052139713, 46520.7673961628, 60121.398786012454, 62104.37895621076, 62111.6894415525, 62195.6890215546, 13084.0, 13279.66800829987, 13360.198388096745, 13360.799588006188, 13385.0, 9949.452780097165, 9992.300538962203, 9997.900020999698, 10001.499925003865, 10004.099631033241, 18626.81373186251, 19313.22747090129, 19356.32252871143, 19523.8047619522, 19549.706754398696, 7544.245575442412, 7617.885731714029, 7652.579108149105, 7742.729004484816, 7851.842963140705, 831.62992468351, 848.5672307123289, 897.8024834536392, 898.0570401078841, 976.8632391465201, 26346.604800928002, 26449.338766530607, 26826.19521414361, 27032.0, 27050.864745676146, 957.9988911587408, 984.9261305401981, 985.8915519292872, 1014.6347314966606, 1106.5241945963153, 13940.721185576434, 14108.198817324457, 14140.0, 14168.716625667428, 14329.713405732036, 260668.0, 262869.0, 263689.0, 264215.0, 264227.0, 44936.0, 45372.009079001284, 45774.5422545777, 45817.312740308924, 45845.31232031455, 1178.7328621731672, 1199.854502693193, 1208.2629595946466, 1210.2133613151345, 1211.1703483114095, 25300.493990119725, 26478.205653830417, 26735.331616709736, 26957.73042269552, 26965.595516067668, 40387.1922561553, 40715.38926916039, 40894.0, 40907.38638920359, 40922.1815563693, 249253.0, 251169.0, 252511.0, 253094.0, 253609.0, 24228.273151805475, 24919.87540062288, 28914.42171156523, 29683.55474667925, 29973.250668733457, 34133.48799768055, 34357.656423435445, 34563.48154777731, 34879.47680784739, 35053.64946350606, 30359.0, 30367.544486832274, 32654.51018234776, 33104.50343244901, 33200.16999574933, 25247.368815779017, 30225.546616801206, 32086.35827283488, 33760.324793504486, 34405.311893761485, 30256.697433026267, 30773.23066923345, 30913.8454307727, 30944.845275773478, 30974.690253097182, 26280.8685956569, 27180.864095679393, 27219.0, 27260.454790903674, 27395.452090958468, 23228.883855580614, 23575.764242357356, 23697.52604947926, 23844.0, 23891.283261502183], "unit": "ms", "name": "ext.html", "value": 16940.88845281776}, {"lowerIsBetter": false, "replicates": [335.2640953107929, 336.49693707906675, 338.94407422775225, 340.180165800421, 340.29898409276876, 12061.819072713894, 12257.632271031884, 14684.19236941969, 16061.919690401546, 16713.74929376059, 14552.563423097297, 14864.182469964147, 14955.252237388142, 15325.540233792975, 15329.693406131899, 3722.162513434469, 4876.707397556149, 4963.652544321899, 4974.228994505857, 5050.090983622947, 4967.751612419383, 5004.84985450437, 5013.172826483626, 5016.372953380827, 5021.297018417425, 4882.926756098661, 4927.531884470969, 4946.084974279752, 4976.4525902150735, 4976.676516026447, 4902.5342592453835, 4941.777620007072, 4948.703077815325, 4949.975250123763, 4950.282209079699, 4431.0, 4431.800568974404, 4439.2675208590545, 4439.955600443986, 4813.879653008667, 11601.129915256304, 11733.765324693459, 11740.060795136369, 11773.823392649118, 15801.288941997638, 1800.0639667372893, 1818.7544681467964, 1833.62410705805, 1842.9815701842945, 1849.056980939717, 9127.087291270886, 9217.216536594398, 9342.486163261017, 9355.0, 11708.12189085822, 1707.214681246628, 1750.5711100780263, 1797.0834874214115, 1806.66576683314, 1848.9722654160198, 51691.48308516904, 53376.46623533754, 54155.45844541543, 54847.0, 54943.725281373736, 9724.465154416434, 9791.559379827924, 9812.215022798302, 9825.066618671357, 9832.0, 10420.270581059252, 10761.56953721858, 11310.377929213972, 12458.190217635825, 12492.50029998808, 5475.6440831345835, 5578.442155784389, 5991.7902873399225, 6828.283030281749, 6884.586924784428, 6439.5170362223025, 6460.612363258219, 6475.125858009203, 6602.1747281589805, 6614.536982411281, 4046.6560342371226, 4054.0675644601906, 4054.736442131252, 4079.0414252650603, 4109.404136400265, 1713.9004719219106, 1730.6365663210615, 1774.1838754173225, 1774.8136445673276, 1793.5695433095964, 823.4770920465428, 863.0290922711949, 891.0599317719867, 908.0057337215738, 988.3822610868208, 14956.0, 15521.223938803014, 15608.687826243411, 15901.443449479213, 16198.0, 823.4153750836973, 824.9893879996956, 840.9950109618946, 847.1613103027958, 945.7730144765206, 7333.706651733977, 8346.958265208636, 8635.006974197922, 8694.608742606599, 8979.236764875059, 502400.0, 506629.0, 509196.0, 528765.0, 535906.0, 56998.86002280014, 58376.708116459144, 62341.37658623356, 63171.0, 64466.677666612304, 453.25213397893737, 461.83604820288986, 461.9630429565694, 462.7339279914063, 464.199256282916, 9351.625934962798, 9431.434113953184, 9624.085711857362, 9696.5636546357, 9911.950440247752, 30585.23536911595, 31062.534061988637, 32469.350612987135, 32507.674923250466, 32700.182495437806, 42780.35829462498, 42974.140517190106, 43043.0, 43582.16715913455, 46522.534774651824, 8667.17661822126, 8831.072737362741, 8916.375853690179, 8983.685571004984, 8993.100689930954, 10520.947395263282, 10631.0, 14899.404023838924, 15123.39506419775, 15764.738820894072, 13374.465021399425, 13458.730825383633, 13550.457981680622, 13587.456501740215, 13601.523946662215, 13122.803157952829, 13164.868351316365, 13450.529231476852, 13614.659633508845, 13735.0, 13718.0, 13882.861171388156, 13915.791263130859, 14350.21073840907, 14598.635034124232, 8827.337949653673, 8981.506017169118, 9082.137196966278, 9222.815543688954, 9224.953875230582, 13020.28388438645, 13143.474261029452, 13339.866601333862, 13630.318484075558, 13786.586402407944], "unit": "ms", "name": "jquery.html", "value": 7732.3486210782385}, {"lowerIsBetter": false, "replicates": [1374.4502199120357, 1551.2631500037483, 1624.4720465848598, 1628.1289510112092, 1662.3932264723371, 5582.944170558293, 6589.571677840937, 6944.02783610295, 6946.409555187815, 6946.47901407394, 6849.4862885283555, 7016.4737644676725, 7025.824354391143, 7032.613206273653, 7036.859262814741, 2086.405374468277, 2114.6722258050027, 2119.9364019079453, 2121.575684863026, 2122.066290832029, 2108.304259594338, 2125.7555381131215, 2135.850490465668, 2144.356692992104, 2149.2262785397243, 2047.508597936492, 2051.2410408149008, 2057.156565808019, 2088.812006919377, 2090.1430413530456, 2044.5399785048394, 2067.5451400691823, 2068.927587534429, 2069.8240649544805, 2071.326818783901, 2018.1927229108474, 2035.3588619584932, 2053.979460205394, 2064.0, 2064.3600483850055, 5636.859078523029, 5802.564807639444, 6921.0, 6995.510314278003, 6997.650117494156, 3956.8615098471705, 4052.9189416211807, 4100.466939297872, 4104.958950410488, 4117.300058989949, 2753.848538330391, 2783.3320003199287, 2800.075974928269, 2817.5914492398692, 2824.7175282471794, 905.9275257979345, 924.1590152960773, 965.0, 970.1559643110469, 1643.531593495848, 9023.142801433853, 9211.124943130391, 9213.262938964866, 9215.308851836138, 9217.216536594398, 3691.354013047706, 3774.905627359338, 3810.7523011004196, 3861.2470568238923, 3867.0525721197923, 7612.429067819936, 7614.657340419694, 7624.19945905683, 7639.732609358646, 7640.541567505968, 4150.086980864205, 4162.62536371728, 4165.062860856283, 4182.163567286494, 4186.58134186585, 3826.445165450994, 3851.287511810324, 3879.689624830007, 3882.0683036071705, 3888.475055867422, 3149.653538110806, 3165.4776961801504, 3177.0786471922884, 3189.936201276008, 3194.9361012780078, 951.2437612098398, 959.2086528613781, 959.8320293948531, 968.9176420004237, 969.5540051576354, 801.9599020048975, 818.7707441916273, 818.8444195602935, 819.512390127873, 912.1881525442361, 6437.871242575122, 6446.935530644727, 6476.255230648441, 6476.902846457307, 6515.120458738104, 429.23595999121613, 433.59892099808184, 440.50883265159115, 442.44031300405015, 448.8272015274143, 4312.504062032844, 5080.923786143211, 5133.307003554547, 5151.8196863109615, 5154.072266991902, 6954.478414118961, 7768.2231776823, 7924.683012679544, 7953.0, 7960.840783184304, 7830.647620857075, 7884.448088633855, 7898.091719452222, 7899.0, 7920.24757648034, 6177.876442471125, 6444.645544495004, 6486.67566621667, 6497.18785151856, 6509.44669703071, 6150.846228844314, 6168.537359697951, 6179.258488981351, 6200.875982480235, 6206.751729930933, 7228.674709638184, 7389.778306650809, 7415.851682966419, 7420.851582968418, 7424.406047516078, 7326.267373262631, 7436.665350059152, 7441.590712510864, 7458.5151965122095, 7616.581088040211, 5975.073863551156, 5982.820515384546, 6060.393960603904, 6061.515078793598, 6063.333033366414, 6393.744250230125, 6468.708908099241, 6470.48236141117, 6471.708773105316, 6498.415142637185, 5951.791687290924, 6000.729967151576, 6027.36712645166, 6117.357677443804, 6131.448169664752, 5794.159846822272, 5928.110783382527, 5975.521958243417, 5980.760769569342, 6062.454379105725, 6080.543959202989, 6119.755209791559, 6125.693715314305, 6195.473384762346, 6256.5307601930945, 5580.637258578179, 5616.129499927518, 5617.9719101404235, 5623.606347555632, 5625.0, 5808.157817116579, 5822.3304320003745, 5856.736446859816, 5861.648301101947, 5868.0], "unit": "ms", "name": "mootools.html", "value": 3860.613572508928}, {"lowerIsBetter": false, "replicates": [300.96388433387995, 309.11746962422285, 310.10070794695366, 311.93137509747856, 315.9921001974952, 5128.102582048136, 5128.948710512894, 5245.816396426126, 5248.13405788045, 5348.839534813957, 5082.771275292612, 5199.662021968569, 5212.061828870802, 5215.86960325992, 5224.164133738603, 3848.8460461581644, 4088.9182216355657, 4093.4678491796176, 4093.713440059197, 4094.7133700640966, 3910.511186101737, 3972.880813575583, 4085.9387109193385, 4088.7342322748927, 4091.7954102294916, 4023.6177563131455, 4040.4747382840337, 4040.7777572233517, 4046.2514434829504, 4049.5545489996075, 4041.1715598302426, 4049.898752531181, 4051.4125451809323, 4053.6757059435467, 4061.411095391152, 3547.3260080584605, 3549.5208146900095, 3552.573691157052, 3554.1470047188745, 3560.145565064366, 4945.0, 5046.369203849519, 5058.3929928408825, 5069.391672999227, 5075.162598171298, 4615.746133962631, 4616.376789133457, 4622.0, 4623.1678297906365, 4623.214053610861, 5431.972840135813, 5461.18082287656, 5467.507924286833, 5470.972645136789, 5474.91787623186, 3572.964270357289, 3639.2357604903004, 3725.8695945642044, 3785.62143785622, 3829.885103446901, 5673.801416950387, 5720.914186287209, 5725.427457254283, 5735.0, 5737.82786516401, 3930.0567863713077, 3934.940975885364, 3935.7244992850224, 3936.996066003208, 3940.8177546735865, 3973.145773658677, 3990.20195960809, 3995.680345572347, 4002.15954649521, 4002.639762421396, 4458.331250312479, 4574.679772415899, 4583.243764778841, 4594.931076033862, 4603.6777425579885, 5479.0, 5487.588430867621, 5489.862753431196, 5492.5331346835965, 5494.670319780826, 1400.3768323096328, 1418.049906562585, 1419.8154239948947, 1420.3395421129223, 1421.076300404745, 954.4654993203828, 958.4393130018877, 961.3126614470686, 977.0669011094387, 980.612658000086, 4848.151573474628, 4859.95140048602, 4860.659753817269, 4861.149298872683, 4864.489228631013, 5695.031844586348, 5697.914531282034, 5701.059325211294, 5710.828675139753, 5715.142728590745, 4851.466338702739, 4857.344258525124, 4858.319835223072, 4858.708477491362, 4859.975700121477, 2997.415503976704, 3057.4955132402906, 3077.569140320357, 3079.030105516755, 3079.076277116856, 5375.139977603643, 5378.300820893337, 5382.865428364322, 5385.192221166778, 5387.649802762808, 5811.302643682785, 5812.534997200214, 5821.15593238978, 5827.562932780059, 5830.8542286442325, 5316.494932981361, 5373.489518495737, 5374.5700343972385, 5382.192671099367, 5383.111786555253, 5530.695811730233, 5671.688057156896, 5675.347335056521, 5681.886362272814, 5694.088945768823, 5386.59600529954, 5511.944880551304, 5523.972380138073, 5545.889082218414, 5564.860878478071, 5299.178627312618, 5332.840014799563, 5337.759800808895, 5344.171653393731, 5402.270693456333, 3929.980350098231, 3945.861894833667, 3945.9408108877815, 3947.585503522204, 3950.762954222641, 5703.971480142739, 5715.114157305624, 5724.656520608777, 5733.254676891977, 5734.885302294014, 5365.892682146413, 5509.338879334506, 5533.944660553504, 5544.334679838284, 5552.361478430033, 5516.365617953827, 5522.392536820867, 5528.91706624409, 5532.834014979557, 5538.750756215899, 5408.864778380572, 5423.0, 5424.457554244544, 5431.782728690966, 5432.049391356418, 5437.782488700408, 5562.110062389998, 5566.916496252478, 5579.916301255404, 5580.497755202051, 5440.401555828935, 5446.264754258283, 5446.591505637014, 5449.5640348772795, 5450.236966824568], "unit": "ms", "name": "prototype.html", "value": 4130.350134167457}, {"lowerIsBetter": false, "replicates": [734.823642325842, 743.2641684732115, 756.882683184106, 766.6051983228638, 770.9575973321469, 11299.774004519904, 11505.0, 11508.079353651709, 11513.136514761385, 11545.191836571434, 10193.796124077515, 11115.388653624048, 11392.316461012346, 11425.42872856358, 11449.656510304705, 752.8494301139766, 759.9202083781179, 762.4738930138187, 766.31797700047, 767.6507189228887, 754.9396048316149, 760.8668483015477, 761.5773245848552, 761.8971438855739, 762.1197516868026, 792.6155814429997, 802.9357651387875, 803.3693550562809, 803.5339503088225, 803.5660743198665, 794.7655441644699, 798.309462315099, 803.638362736765, 803.7950322667682, 804.1958041958042, 591.1428428778246, 593.9079442686391, 595.6247564034674, 595.7051259626512, 596.1981135372916, 11724.355160466172, 11757.529698812037, 11757.588484403004, 11878.643640690792, 11987.64037078889, 11166.385848778315, 11590.07279417653, 11611.071114310835, 11661.6501504955, 12086.033117350591, 9062.139096785795, 9116.954415227949, 9121.908780912172, 9312.674056408061, 9492.715218543455, 3201.359728054375, 3254.30032543002, 3267.5425440438357, 3286.063471910518, 3348.296857659894, 34551.13622159441, 34783.30433391318, 39325.0, 42214.0, 42894.78552607248, 9343.392679475948, 9351.859722104175, 9391.765205869771, 9583.377080489885, 9661.855072173783, 17034.574135646708, 17052.2326495308, 17070.743938840926, 17115.0, 17121.743173852403, 6505.772297969643, 6533.836654083686, 6568.835779105465, 6570.507211959122, 6642.634655094016, 3633.2006958469096, 3662.8351724172476, 3682.392405253157, 3699.0197597636648, 3740.2706472238197, 2757.352022274764, 2786.5680819473014, 2789.804713670024, 2791.330080780598, 2794.0, 479.11124863378626, 482.85755702067974, 485.8226747237233, 493.24040976895895, 501.22560643805406, 442.6591524526097, 444.7709429643736, 455.8267858213857, 478.6840685147786, 485.75226634416, 7403.7408690695565, 7474.626268686544, 7560.103219121424, 7644.197359277307, 7808.570528620867, 534.1453674121452, 545.8171512543263, 556.121328301283, 567.0841590830861, 570.2985328046526, 3381.1040074380307, 3457.5505184325875, 3535.027867336503, 3590.174259920233, 3661.1945372018113, 48962.02075958532, 49305.50694493009, 49460.75269623629, 49541.752291238314, 49947.25079123816, 42341.78829105835, 42974.785126074166, 43565.34651980223, 43836.780816095714, 44073.338899916525, 39168.2166356677, 39258.803705981285, 40522.0, 44969.775151124035, 45447.318290225674, 7423.554586724814, 7618.276263754934, 7666.9616651916385, 7766.495177813424, 7772.339351154989, 31014.68985310118, 31506.684933150376, 31648.841755791073, 31898.52152217672, 31943.840280798446, 35447.291054178255, 36384.454233187054, 38472.230555388174, 39177.216455671296, 39524.604753952095, 7836.686532538634, 8922.643094276156, 9153.130452606993, 9203.263738900738, 9340.906590934004, 9935.950320248352, 10175.440350780778, 10371.325863818827, 10411.687649370531, 10761.354318740901, 10055.19558435338, 10187.847182292124, 10270.743231419274, 10313.690589282334, 10478.056974872297, 10024.398536087858, 10175.694729158136, 10234.385936843813, 10308.845367319345, 10329.328593641389, 9742.659006934724, 9812.969638188173, 9828.4102953823, 9933.0, 10167.288289819937, 8487.066422693622, 8542.0, 8550.57247137653, 8601.35489838252, 8882.644694212158, 7535.849283014419, 7928.722494712657, 8054.718084867001, 8139.633716482891, 8255.298299644597], "unit": "ms", "name": "yui.html", "value": 5562.258286161273}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 112.0, 109.0, 114.0, 114.0, 115.0, 100.0, 111.0, 110.0, 105.0], "unit": "ms", "name": "ai-astar", "value": 110.1}, {"lowerIsBetter": true, "replicates": [156.0, 137.0, 154.0, 165.0, 131.0, 162.0, 160.0, 129.0, 183.0, 135.0], "unit": "ms", "name": "audio-beat-detection", "value": 151.2}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 161.0, 160.0, 160.0, 160.0, 158.0, 161.0, 164.0, 162.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 80.0, 95.0, 73.0, 73.0, 72.0, 96.0, 94.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 140.0, 94.0, 94.0, 95.0, 134.0, 94.0, 94.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 70.0, 71.0, 71.0, 70.0, 70.0, 82.0, 69.0, 67.0], "unit": "ms", "name": "json-parse-financial", "value": 71.1}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 69.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [84.0, 84.0, 84.0, 83.0, 82.0, 83.0, 82.0, 82.0, 86.0, 91.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.1}, {"lowerIsBetter": true, "replicates": [130.0, 131.0, 127.0, 123.0, 119.0, 121.0, 124.0, 136.0, 124.0, 130.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 126.5}, {"lowerIsBetter": true, "replicates": [150.0, 148.0, 151.0, 148.0, 148.0, 136.0, 149.0, 151.0, 148.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.5}, {"lowerIsBetter": true, "replicates": [77.0, 69.0, 69.0, 69.0, 69.0, 70.0, 68.0, 70.0, 68.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 69.7}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.52780519907864], "unit": "score", "name": "Richards", "value": 214.56555600000002}, {"lowerIsBetter": false, "replicates": [191.3143294432753], "unit": "score", "name": "DeltaBlue", "value": 345.5987859999999}, {"lowerIsBetter": false, "replicates": [277.77777777777777], "unit": "score", "name": "Encrypt", "value": 958.2516000000002}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [1982.1782178217823], "unit": "score", "name": "RayTrace", "value": 373.32112387612375}, {"lowerIsBetter": false, "replicates": [793.0214115781126], "unit": "score", "name": "Earley", "value": 840.4098429999997}, {"lowerIsBetter": false, "replicates": [12456.79012345679], "unit": "score", "name": "Boyer", "value": 53.501985133795806}, {"lowerIsBetter": false, "replicates": [26473.684210526317], "unit": "score", "name": "RegExp", "value": 34.410964214711704}, {"lowerIsBetter": false, "replicates": [598.0861244019138], "unit": "score", "name": "Splay", "value": 136.25295200000005}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7"}]} 18:27:28 INFO - RETURN: dromaeo_css: 6116.21 18:27:28 INFO - RETURN: kraken: 1525.80 18:27:28 INFO - RETURN: v8_7: 19792.27 18:27:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,35]]", "result": "19792.27"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,35]]", "result": "1525.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,35]]", "result": "6116.21"}}} 18:27:28 INFO - Return code: 0 18:27:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:27:28 INFO - # TBPL SUCCESS # 18:27:28 INFO - Running post-action listener: _resource_record_post_action 18:27:28 INFO - Running post-run listener: _resource_record_post_run 18:27:29 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 516292608; Read time: 16; Write time: 531980 18:27:29 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 0; Write bytes: 240185344; Read time: 0; Write time: 332756 18:27:29 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 272130048; Read time: 0; Write time: 197524 18:27:29 INFO - Running post-run listener: _upload_blobber_files 18:27:29 INFO - Blob upload gear active. 18:27:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:27:29 INFO - Copying logs to upload dir... 18:27:29 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1266.954422 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-11-24 18:27:29.449021) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 18:27:29.452550) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010005 build_url: 'https://queue.taskcluster.net/v1/task/-GjJps0-SLCPUOQHPQ_how/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-24 18:27:29.504417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:27:29.504760) ========= 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=7d909545e45784438f4e8e36000001b3-1448416289.40203-1285951076 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 18:27:29.555694) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 18:27:29.556176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 18:27:29.556573) ========= ========= Total master_lag: 0.24 =========