builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-035 starttime: 1448442936.03 results: success (0) buildid: 20151124233732 builduid: a9dc3de5ea364f8f9c1808a51a95bbe6 revision: 8233c8116679022ce8ac046186058b9dd691d50c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.034528) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.034954) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.035318) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.003414 basedir: '/builds/slave/test' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.077450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.077743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.507682) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.507961) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004161 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.570526) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.570809) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.574231) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.574558) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 01:15:36-- 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% 43.6M=0s 2015-11-25 01:15:36 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.181837 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.775881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.776213) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014727 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.831604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:36.831960) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8233c8116679022ce8ac046186058b9dd691d50c --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 8233c8116679022ce8ac046186058b9dd691d50c --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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 01:15:37,092 truncating revision to first 12 chars 2015-11-25 01:15:37,092 Setting DEBUG logging. 2015-11-25 01:15:37,092 attempt 1/10 2015-11-25 01:15:37,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8233c8116679?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 01:15:37,344 unpacking tar archive at: fx-team-8233c8116679/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540429 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:37.393133) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:37.393570) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:37.403828) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:37.404080) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 01:15:37.404417) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2015-11-25 01:15:37.404685) ========= /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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:15:37 INFO - MultiFileLogger online at 20151125 01:15:37 in /builds/slave/test 01:15:37 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 01:15:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:15:37 INFO - {'append_to_log': False, 01:15:37 INFO - 'base_work_dir': '/builds/slave/test', 01:15:37 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:15:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:15:37 INFO - 'branch': 'Fx-Team-Non-PGO', 01:15:37 INFO - 'buildbot_json_path': 'buildprops.json', 01:15:37 INFO - 'config_files': ('talos/linux_config.py',), 01:15:37 INFO - 'default_actions': ('clobber', 01:15:37 INFO - 'read-buildbot-config', 01:15:37 INFO - 'download-and-extract', 01:15:37 INFO - 'populate-webroot', 01:15:37 INFO - 'create-virtualenv', 01:15:37 INFO - 'install', 01:15:37 INFO - 'run-tests'), 01:15:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:15:37 INFO - 'download_minidump_stackwalk': True, 01:15:37 INFO - 'download_symbols': 'ondemand', 01:15:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:15:37 INFO - 'tooltool.py': '/tools/tooltool.py', 01:15:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:15:37 INFO - '/tools/misc-python/virtualenv.py')}, 01:15:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:15:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:15:37 INFO - 'installer_path': 'installer.exe', 01:15:37 INFO - 'log_level': 'info', 01:15:37 INFO - 'log_name': 'talos', 01:15:37 INFO - 'log_to_console': True, 01:15:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:15:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:15:37 INFO - 'opt_config_files': (), 01:15:37 INFO - 'pip_index': False, 01:15:37 INFO - 'sps_profile': False, 01:15:37 INFO - 'sps_profile_interval': 0, 01:15:37 INFO - 'suite': 'dromaeojs', 01:15:37 INFO - 'system_bits': '64', 01:15:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:15:37 INFO - 'title': 'talos-linux64-ix-035', 01:15:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:15:37 INFO - 'use_talos_json': True, 01:15:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:15:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:15:37 INFO - 'work_dir': 'build'} 01:15:37 INFO - ##### 01:15:37 INFO - ##### Running clobber step. 01:15:37 INFO - ##### 01:15:37 INFO - Running pre-action listener: _resource_record_pre_action 01:15:37 INFO - Running main action method: clobber 01:15:37 INFO - rmtree: /builds/slave/test/build 01:15:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:15:38 INFO - Running post-action listener: _resource_record_post_action 01:15:38 INFO - ##### 01:15:38 INFO - ##### Running read-buildbot-config step. 01:15:38 INFO - ##### 01:15:38 INFO - Running pre-action listener: _resource_record_pre_action 01:15:38 INFO - Running main action method: read_buildbot_config 01:15:38 INFO - Using buildbot properties: 01:15:38 INFO - { 01:15:38 INFO - "properties": { 01:15:38 INFO - "buildnumber": 2121, 01:15:38 INFO - "product": "firefox", 01:15:38 INFO - "script_repo_revision": "production", 01:15:38 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 01:15:38 INFO - "repository": "", 01:15:38 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 01:15:38 INFO - "buildid": "20151124233732", 01:15:38 INFO - "slavename": "talos-linux64-ix-035", 01:15:38 INFO - "pgo_build": "False", 01:15:38 INFO - "basedir": "/builds/slave/test", 01:15:38 INFO - "project": "", 01:15:38 INFO - "platform": "ubuntu64_hw", 01:15:38 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:15:38 INFO - "slavebuilddir": "test", 01:15:38 INFO - "scheduler": "tests-fx-team-linux64-talos", 01:15:38 INFO - "branch": "fx-team", 01:15:38 INFO - "repo_path": "integration/fx-team", 01:15:38 INFO - "stage_platform": "linux64", 01:15:38 INFO - "builduid": "a9dc3de5ea364f8f9c1808a51a95bbe6", 01:15:38 INFO - "revision": "8233c8116679022ce8ac046186058b9dd691d50c" 01:15:38 INFO - }, 01:15:38 INFO - "sourcestamp": { 01:15:38 INFO - "repository": "", 01:15:38 INFO - "hasPatch": false, 01:15:38 INFO - "project": "", 01:15:38 INFO - "branch": "fx-team-linux64-talos", 01:15:38 INFO - "changes": [ 01:15:38 INFO - { 01:15:38 INFO - "category": null, 01:15:38 INFO - "files": [ 01:15:38 INFO - { 01:15:38 INFO - "url": null, 01:15:38 INFO - "name": "https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:15:38 INFO - } 01:15:38 INFO - ], 01:15:38 INFO - "repository": "", 01:15:38 INFO - "rev": "8233c8116679022ce8ac046186058b9dd691d50c", 01:15:38 INFO - "who": "cbook@mozilla.com", 01:15:38 INFO - "when": 1448442892, 01:15:38 INFO - "number": 6704684, 01:15:38 INFO - "comments": "Bug 1218022 - switch to fx_desktop_v2 context for Firefox Accounts. r=markh", 01:15:38 INFO - "project": "", 01:15:38 INFO - "at": "Wed 25 Nov 2015 01:14:52", 01:15:38 INFO - "branch": "fx-team-linux64-talos", 01:15:38 INFO - "revlink": "", 01:15:38 INFO - "properties": [ 01:15:38 INFO - [ 01:15:38 INFO - "buildid", 01:15:38 INFO - "20151124233732", 01:15:38 INFO - "Change" 01:15:38 INFO - ], 01:15:38 INFO - [ 01:15:38 INFO - "builduid", 01:15:38 INFO - "a9dc3de5ea364f8f9c1808a51a95bbe6", 01:15:38 INFO - "Change" 01:15:38 INFO - ], 01:15:38 INFO - [ 01:15:38 INFO - "pgo_build", 01:15:38 INFO - "False", 01:15:38 INFO - "Change" 01:15:38 INFO - ] 01:15:38 INFO - ], 01:15:38 INFO - "revision": "8233c8116679022ce8ac046186058b9dd691d50c" 01:15:38 INFO - } 01:15:38 INFO - ], 01:15:38 INFO - "revision": "8233c8116679022ce8ac046186058b9dd691d50c" 01:15:38 INFO - } 01:15:38 INFO - } 01:15:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 01:15:38 INFO - Running post-action listener: _resource_record_post_action 01:15:38 INFO - ##### 01:15:38 INFO - ##### Running download-and-extract step. 01:15:38 INFO - ##### 01:15:38 INFO - Running pre-action listener: _resource_record_pre_action 01:15:38 INFO - Running main action method: download_and_extract 01:15:38 INFO - mkdir: /builds/slave/test/build/tests 01:15: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')]} 01:15:38 INFO - https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:15:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/test_packages.json 01:15:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/test_packages.json 01:15:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 01:15:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 01:15:39 INFO - Downloaded 1302 bytes. 01:15:39 INFO - Reading from file /builds/slave/test/build/test_packages.json 01:15:39 INFO - Using the following test package requirements: 01:15:39 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:15:39 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:15:39 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'jsshell-linux-x86_64.zip'], 01:15:39 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:15:39 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:15:39 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:15:39 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:15:39 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:15:39 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:15:39 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:15:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:15:39 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:15: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')]} 01:15:39 INFO - https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:15:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:41 INFO - Downloaded 21345683 bytes. 01:15:41 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 01:15:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:41 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 01:15:42 INFO - Return code: 0 01:15:42 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 01:15:42 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')]} 01:15:42 INFO - https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:15:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:44 INFO - Downloaded 21345683 bytes. 01:15:44 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 01:15:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:15:44 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 01:15:46 INFO - Return code: 0 01:15: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')]} 01:15:46 INFO - https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:15:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:15:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:15:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:50 INFO - Downloaded 11131717 bytes. 01:15:50 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 01:15:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:15:50 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 01:15:50 INFO - Return code: 0 01:15: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')]} 01:15:50 INFO - https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:15:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:15:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:15:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2L9KRq_2QOan3YsW4JnRTw/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 01:15:53 INFO - Downloaded 56844622 bytes. 01:15:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:15:53 INFO - mkdir: /builds/slave/test/properties 01:15:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:15:53 INFO - Writing to file /builds/slave/test/properties/build_url 01:15:53 INFO - Contents: 01:15:53 INFO - build_url:https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:15:53 INFO - Running post-action listener: _resource_record_post_action 01:15:53 INFO - Running post-action listener: set_extra_try_arguments 01:15:53 INFO - ##### 01:15:53 INFO - ##### Running populate-webroot step. 01:15:53 INFO - ##### 01:15:53 INFO - Running pre-action listener: _resource_record_pre_action 01:15:53 INFO - Running main action method: populate_webroot 01:15:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:15:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:15:53 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:15:53 INFO - '../fennec_ids.txt'], 01:15:53 INFO - 'tests': ['tcheck2']}, 01:15:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:15:53 INFO - '--tppagecycles', 01:15:53 INFO - '7'], 01:15:53 INFO - 'tests': ['tsvgm']}}, 01:15:53 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:15:53 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:15:53 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:15:53 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:15:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:15:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'tests': ['damp', 'tps']}, 01:15:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['damp', 'tps']}, 01:15:53 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:15:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:15:53 INFO - 'other': {'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'other_l64': {'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'other_nol64': {'tests': ['a11yr', 01:15:53 INFO - 'ts_paint', 01:15:53 INFO - 'tpaint', 01:15:53 INFO - 'sessionrestore', 01:15:53 INFO - 'sessionrestore_no_auto_restore']}, 01:15:53 INFO - 'svgr': {'tests': ['tsvgx', 01:15:53 INFO - 'tsvgr_opacity', 01:15:53 INFO - 'tart', 01:15:53 INFO - 'tscrollx', 01:15:53 INFO - 'cart']}, 01:15:53 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['tsvgx', 01:15:53 INFO - 'tsvgr_opacity', 01:15:53 INFO - 'tart', 01:15:53 INFO - 'tscrollx', 01:15:53 INFO - 'cart']}, 01:15:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'tests': ['tp5o']}, 01:15:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'talos_options': ['--e10s'], 01:15:53 INFO - 'tests': ['tp5o']}, 01:15:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'talos_options': ['--xperf_path', 01:15:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:53 INFO - 'tests': ['tp5n']}, 01:15:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:15:53 INFO - 'talos_options': ['--e10s', 01:15:53 INFO - '--xperf_path', 01:15:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:15:53 INFO - 'tests': ['tp5n']}}, 01:15:53 INFO - 'talos.zip': {'path': '', 01:15:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:15:53 INFO - Running post-action listener: _resource_record_post_action 01:15:53 INFO - ##### 01:15:53 INFO - ##### Running create-virtualenv step. 01:15:53 INFO - ##### 01:15:53 INFO - Running pre-action listener: _resource_record_pre_action 01:15:53 INFO - Running main action method: create_virtualenv 01:15:53 INFO - Creating virtualenv /builds/slave/test/build/venv 01:15:53 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 01:15:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:15:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:15:53 INFO - Using real prefix '/usr' 01:15:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:15:53 INFO - Installing distribute.............................................................................................................................................................................................done. 01:15:55 INFO - Installing pip.................done. 01:15:55 INFO - Return code: 0 01:15:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:55 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:15:55 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 01:15:55 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 01:15:55 INFO - Using env: {'DISPLAY': ':0', 01:15:55 INFO - 'HOME': '/home/cltbld', 01:15:55 INFO - 'LANG': 'en_US.UTF-8', 01:15:55 INFO - 'LANGUAGE': 'en_US:en', 01:15:55 INFO - 'LOGNAME': 'cltbld', 01:15:55 INFO - 'MAIL': '/var/mail/cltbld', 01:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:55 INFO - 'MOZ_NO_REMOTE': '1', 01:15:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:55 INFO - 'NO_EM_RESTART': '1', 01:15:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:55 INFO - 'PWD': '/builds/slave/test', 01:15:55 INFO - 'SHELL': '/bin/bash', 01:15:55 INFO - 'SHLVL': '1', 01:15:55 INFO - 'TERM': 'linux', 01:15:55 INFO - 'TMOUT': '86400', 01:15:55 INFO - 'USER': 'cltbld', 01:15:55 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:55 INFO - '_': '/tools/buildbot/bin/python'} 01:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:56 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 01:15:56 INFO - Cleaning up... 01:15:56 INFO - Return code: 0 01:15:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:15:56 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')]} 01:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:56 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')]} 01:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:56 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:15:56 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 01:15:56 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 01:15:56 INFO - Using env: {'DISPLAY': ':0', 01:15:56 INFO - 'HOME': '/home/cltbld', 01:15:56 INFO - 'LANG': 'en_US.UTF-8', 01:15:56 INFO - 'LANGUAGE': 'en_US:en', 01:15:56 INFO - 'LOGNAME': 'cltbld', 01:15:56 INFO - 'MAIL': '/var/mail/cltbld', 01:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:56 INFO - 'MOZ_NO_REMOTE': '1', 01:15:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:56 INFO - 'NO_EM_RESTART': '1', 01:15:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:56 INFO - 'PWD': '/builds/slave/test', 01:15:56 INFO - 'SHELL': '/bin/bash', 01:15:56 INFO - 'SHLVL': '1', 01:15:56 INFO - 'TERM': 'linux', 01:15:56 INFO - 'TMOUT': '86400', 01:15:56 INFO - 'USER': 'cltbld', 01:15:56 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:56 INFO - '_': '/tools/buildbot/bin/python'} 01:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:56 INFO - Downloading/unpacking psutil>=0.7.1 01:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:58 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 01:15:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:15:58 INFO - Installing collected packages: psutil 01:15:58 INFO - Running setup.py install for psutil 01:15:58 INFO - building 'psutil._psutil_linux' extension 01:15:58 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 01:15:58 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 01:15:58 INFO - building 'psutil._psutil_posix' extension 01:15:58 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 01:15:58 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 01:15:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:15:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:15:58 INFO - Successfully installed psutil 01:15:58 INFO - Cleaning up... 01:15:58 INFO - Return code: 0 01:15:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:15:58 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:15:58 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 01:15:58 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 01:15:58 INFO - Using env: {'DISPLAY': ':0', 01:15:58 INFO - 'HOME': '/home/cltbld', 01:15:58 INFO - 'LANG': 'en_US.UTF-8', 01:15:58 INFO - 'LANGUAGE': 'en_US:en', 01:15:58 INFO - 'LOGNAME': 'cltbld', 01:15:58 INFO - 'MAIL': '/var/mail/cltbld', 01:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:15:58 INFO - 'MOZ_NO_REMOTE': '1', 01:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:58 INFO - 'NO_EM_RESTART': '1', 01:15:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:58 INFO - 'PWD': '/builds/slave/test', 01:15:58 INFO - 'SHELL': '/bin/bash', 01:15:58 INFO - 'SHLVL': '1', 01:15:58 INFO - 'TERM': 'linux', 01:15:58 INFO - 'TMOUT': '86400', 01:15:58 INFO - 'USER': 'cltbld', 01:15:58 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:15:58 INFO - '_': '/tools/buildbot/bin/python'} 01:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:15:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:16:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:16:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:16:00 INFO - Installing collected packages: mozsystemmonitor 01:16:00 INFO - Running setup.py install for mozsystemmonitor 01:16:00 INFO - Successfully installed mozsystemmonitor 01:16:00 INFO - Cleaning up... 01:16:00 INFO - Return code: 0 01:16:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:00 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:16:00 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 01:16:00 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 01:16:00 INFO - Using env: {'DISPLAY': ':0', 01:16:00 INFO - 'HOME': '/home/cltbld', 01:16:00 INFO - 'LANG': 'en_US.UTF-8', 01:16:00 INFO - 'LANGUAGE': 'en_US:en', 01:16:00 INFO - 'LOGNAME': 'cltbld', 01:16:00 INFO - 'MAIL': '/var/mail/cltbld', 01:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:00 INFO - 'MOZ_NO_REMOTE': '1', 01:16:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:00 INFO - 'NO_EM_RESTART': '1', 01:16:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:00 INFO - 'PWD': '/builds/slave/test', 01:16:00 INFO - 'SHELL': '/bin/bash', 01:16:00 INFO - 'SHLVL': '1', 01:16:00 INFO - 'TERM': 'linux', 01:16:00 INFO - 'TMOUT': '86400', 01:16:00 INFO - 'USER': 'cltbld', 01:16:00 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:00 INFO - '_': '/tools/buildbot/bin/python'} 01:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:00 INFO - Downloading/unpacking blobuploader==1.2.4 01:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:02 INFO - Downloading blobuploader-1.2.4.tar.gz 01:16:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:16:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:16:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:16:03 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 01:16:03 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 01:16:03 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 01:16:03 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 01:16:03 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 01:16:03 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 01:16:03 INFO - Downloading docopt-0.6.1.tar.gz 01:16:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:16:03 INFO - Installing collected packages: blobuploader, requests, docopt 01:16:03 INFO - Running setup.py install for blobuploader 01:16:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:16:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:16:03 INFO - Running setup.py install for requests 01:16:03 INFO - Running setup.py install for docopt 01:16:03 INFO - Successfully installed blobuploader requests docopt 01:16:03 INFO - Cleaning up... 01:16:03 INFO - Return code: 0 01:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:03 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:16:03 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 01:16:03 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 01:16:03 INFO - Using env: {'DISPLAY': ':0', 01:16:03 INFO - 'HOME': '/home/cltbld', 01:16:03 INFO - 'LANG': 'en_US.UTF-8', 01:16:03 INFO - 'LANGUAGE': 'en_US:en', 01:16:03 INFO - 'LOGNAME': 'cltbld', 01:16:03 INFO - 'MAIL': '/var/mail/cltbld', 01:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:03 INFO - 'MOZ_NO_REMOTE': '1', 01:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:03 INFO - 'NO_EM_RESTART': '1', 01:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:03 INFO - 'PWD': '/builds/slave/test', 01:16:03 INFO - 'SHELL': '/bin/bash', 01:16:03 INFO - 'SHLVL': '1', 01:16:03 INFO - 'TERM': 'linux', 01:16:03 INFO - 'TMOUT': '86400', 01:16:03 INFO - 'USER': 'cltbld', 01:16:03 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:03 INFO - '_': '/tools/buildbot/bin/python'} 01:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:16:04 INFO - Running setup.py (path:/tmp/pip-JEiglv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:16:04 INFO - Running setup.py (path:/tmp/pip-tqcuNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:16:04 INFO - Running setup.py (path:/tmp/pip-oM8zho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:16:04 INFO - Running setup.py (path:/tmp/pip-UCEaTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:16:04 INFO - Running setup.py (path:/tmp/pip-_SprlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:16:04 INFO - Running setup.py (path:/tmp/pip-5uZAOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:16:04 INFO - Running setup.py (path:/tmp/pip-RBCQEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:16:04 INFO - Running setup.py (path:/tmp/pip-pNF2Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:16:04 INFO - Running setup.py (path:/tmp/pip-7gDDi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:16:04 INFO - Running setup.py (path:/tmp/pip-ymMwSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:16:04 INFO - Running setup.py (path:/tmp/pip-SyBSiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:16:04 INFO - Running setup.py (path:/tmp/pip-vDIhEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:16:05 INFO - Running setup.py (path:/tmp/pip-Lnrgxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:16:05 INFO - Running setup.py (path:/tmp/pip-BtoSHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:05 INFO - Running setup.py (path:/tmp/pip-F2q_68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:16:05 INFO - Running setup.py (path:/tmp/pip-sZ5SrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:16:05 INFO - Running setup.py (path:/tmp/pip-iH3dm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:16:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:16:05 INFO - Running setup.py install for manifestparser 01:16:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:16:05 INFO - Running setup.py install for mozcrash 01:16:05 INFO - Running setup.py install for mozdebug 01:16:05 INFO - Running setup.py install for mozdevice 01:16:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:16:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:16:05 INFO - Running setup.py install for mozfile 01:16:05 INFO - Running setup.py install for mozhttpd 01:16:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:16:05 INFO - Running setup.py install for mozinfo 01:16:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for mozInstall 01:16:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for mozleak 01:16:06 INFO - Running setup.py install for mozlog 01:16:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for moznetwork 01:16:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for mozprocess 01:16:06 INFO - Running setup.py install for mozprofile 01:16:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for mozrunner 01:16:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:16:06 INFO - Running setup.py install for mozscreenshot 01:16:06 INFO - Running setup.py install for moztest 01:16:07 INFO - Running setup.py install for mozversion 01:16:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:16:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:16:07 INFO - Cleaning up... 01:16:07 INFO - Return code: 0 01:16:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:16:07 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 01:16:07 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 01:16:07 INFO - Using env: {'DISPLAY': ':0', 01:16:07 INFO - 'HOME': '/home/cltbld', 01:16:07 INFO - 'LANG': 'en_US.UTF-8', 01:16:07 INFO - 'LANGUAGE': 'en_US:en', 01:16:07 INFO - 'LOGNAME': 'cltbld', 01:16:07 INFO - 'MAIL': '/var/mail/cltbld', 01:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:07 INFO - 'MOZ_NO_REMOTE': '1', 01:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:07 INFO - 'NO_EM_RESTART': '1', 01:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:07 INFO - 'PWD': '/builds/slave/test', 01:16:07 INFO - 'SHELL': '/bin/bash', 01:16:07 INFO - 'SHLVL': '1', 01:16:07 INFO - 'TERM': 'linux', 01:16:07 INFO - 'TMOUT': '86400', 01:16:07 INFO - 'USER': 'cltbld', 01:16:07 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:07 INFO - '_': '/tools/buildbot/bin/python'} 01:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:16:07 INFO - Running setup.py (path:/tmp/pip-HCsJVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:16:07 INFO - Running setup.py (path:/tmp/pip-xWl1Wj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:16:07 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)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:16:07 INFO - Running setup.py (path:/tmp/pip-lsjfP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:16:07 INFO - Running setup.py (path:/tmp/pip-x8XiU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:16:07 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)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:16:07 INFO - Running setup.py (path:/tmp/pip-HQT4wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:16:07 INFO - Running setup.py (path:/tmp/pip-n5QOg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:16:07 INFO - Running setup.py (path:/tmp/pip-uEHzTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:16:07 INFO - Running setup.py (path:/tmp/pip-l6H0ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:16:07 INFO - Running setup.py (path:/tmp/pip-_18jcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:16:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:16:07 INFO - Running setup.py (path:/tmp/pip-74V2RE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:16:08 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)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:16:08 INFO - Running setup.py (path:/tmp/pip-UEMBz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:16:08 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)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:16:08 INFO - Running setup.py (path:/tmp/pip-7eoxMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:16:08 INFO - Running setup.py (path:/tmp/pip-jYj6Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:16:08 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)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:16:08 INFO - Running setup.py (path:/tmp/pip-in2Pvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:08 INFO - Running setup.py (path:/tmp/pip-BYAFKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:16:08 INFO - Running setup.py (path:/tmp/pip-W9y0M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:16:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:16:08 INFO - Running setup.py (path:/tmp/pip-GOYTqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:16:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:10 INFO - Downloading blessings-1.5.1.tar.gz 01:16:10 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 01:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:16:10 INFO - Installing collected packages: blessings 01:16:10 INFO - Running setup.py install for blessings 01:16:10 INFO - Successfully installed blessings 01:16:10 INFO - Cleaning up... 01:16:10 INFO - Return code: 0 01:16:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:16:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:16:10 INFO - Reading from file tmpfile_stdout 01:16:10 INFO - Current package versions: 01:16:10 INFO - argparse == 1.2.1 01:16:10 INFO - blessings == 1.5.1 01:16:10 INFO - blobuploader == 1.2.4 01:16:10 INFO - docopt == 0.6.1 01:16:10 INFO - manifestparser == 1.1 01:16:10 INFO - mozInstall == 1.12 01:16:10 INFO - mozcrash == 0.16 01:16:10 INFO - mozdebug == 0.1 01:16:10 INFO - mozdevice == 0.47 01:16:10 INFO - mozfile == 1.2 01:16:10 INFO - mozhttpd == 0.7 01:16:10 INFO - mozinfo == 0.9 01:16:10 INFO - mozleak == 0.1 01:16:10 INFO - mozlog == 3.1 01:16:10 INFO - moznetwork == 0.27 01:16:10 INFO - mozprocess == 0.22 01:16:10 INFO - mozprofile == 0.27 01:16:10 INFO - mozrunner == 6.11 01:16:10 INFO - mozscreenshot == 0.1 01:16:10 INFO - mozsystemmonitor == 0.0 01:16:10 INFO - moztest == 0.7 01:16:10 INFO - mozversion == 1.4 01:16:10 INFO - psutil == 3.1.1 01:16:10 INFO - requests == 1.2.3 01:16:10 INFO - wsgiref == 0.1.2 01:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:16: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')]} 01:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16: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')]} 01:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:10 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:16:10 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 01:16:10 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 01:16:10 INFO - Using env: {'DISPLAY': ':0', 01:16:10 INFO - 'HOME': '/home/cltbld', 01:16:10 INFO - 'LANG': 'en_US.UTF-8', 01:16:10 INFO - 'LANGUAGE': 'en_US:en', 01:16:10 INFO - 'LOGNAME': 'cltbld', 01:16:10 INFO - 'MAIL': '/var/mail/cltbld', 01:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:10 INFO - 'MOZ_NO_REMOTE': '1', 01:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:10 INFO - 'NO_EM_RESTART': '1', 01:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:10 INFO - 'PWD': '/builds/slave/test', 01:16:10 INFO - 'SHELL': '/bin/bash', 01:16:10 INFO - 'SHLVL': '1', 01:16:10 INFO - 'TERM': 'linux', 01:16:10 INFO - 'TMOUT': '86400', 01:16:10 INFO - 'USER': 'cltbld', 01:16:10 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:10 INFO - '_': '/tools/buildbot/bin/python'} 01:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 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)) 01:16:10 INFO - Cleaning up... 01:16:10 INFO - Return code: 0 01:16:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:16: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')]} 01:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16: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')]} 01:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:16:10 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 0xf40030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1171210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', '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 01:16:10 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 01:16:10 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 01:16:10 INFO - Using env: {'DISPLAY': ':0', 01:16:10 INFO - 'HOME': '/home/cltbld', 01:16:10 INFO - 'LANG': 'en_US.UTF-8', 01:16:10 INFO - 'LANGUAGE': 'en_US:en', 01:16:10 INFO - 'LOGNAME': 'cltbld', 01:16:10 INFO - 'MAIL': '/var/mail/cltbld', 01:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:10 INFO - 'MOZ_NO_REMOTE': '1', 01:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:10 INFO - 'NO_EM_RESTART': '1', 01:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:10 INFO - 'PWD': '/builds/slave/test', 01:16:10 INFO - 'SHELL': '/bin/bash', 01:16:10 INFO - 'SHLVL': '1', 01:16:10 INFO - 'TERM': 'linux', 01:16:10 INFO - 'TMOUT': '86400', 01:16:10 INFO - 'USER': 'cltbld', 01:16:10 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:10 INFO - '_': '/tools/buildbot/bin/python'} 01:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:16:11 INFO - Downloading/unpacking jsonschema 01:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:16:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:16:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:16:12 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 01:16:12 INFO - Downloading/unpacking functools32 (from jsonschema) 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 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 01:16:12 INFO - Downloading functools32-3.2.3-2.tar.gz 01:16:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 01:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:16:13 INFO - warning: no files found matching '*.txt' 01:16:13 INFO - no previously-included directories found matching 'build' 01:16:13 INFO - no previously-included directories found matching 'dist' 01:16:13 INFO - no previously-included directories found matching '.git*' 01:16:13 INFO - Installing collected packages: jsonschema, functools32 01:16:13 INFO - Running setup.py install for functools32 01:16:13 INFO - warning: no files found matching '*.txt' 01:16:13 INFO - no previously-included directories found matching 'build' 01:16:13 INFO - no previously-included directories found matching 'dist' 01:16:13 INFO - no previously-included directories found matching '.git*' 01:16:13 INFO - Successfully installed jsonschema functools32 01:16:13 INFO - Cleaning up... 01:16:13 INFO - Return code: 0 01:16:13 INFO - Running post-action listener: _resource_record_post_action 01:16:13 INFO - Running post-action listener: _start_resource_monitoring 01:16:13 INFO - Starting resource monitoring. 01:16:13 INFO - ##### 01:16:13 INFO - ##### Running install step. 01:16:13 INFO - ##### 01:16:13 INFO - Running pre-action listener: _resource_record_pre_action 01:16:13 INFO - Running main action method: install 01:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:16:13 INFO - Reading from file tmpfile_stdout 01:16:13 INFO - Detecting whether we're running mozinstall >=1.0... 01:16:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:16:13 INFO - Reading from file tmpfile_stdout 01:16:13 INFO - Output received: 01:16:13 INFO - Usage: mozinstall [options] installer 01:16:13 INFO - Options: 01:16:13 INFO - -h, --help show this help message and exit 01:16:13 INFO - -d DEST, --destination=DEST 01:16:13 INFO - Directory to install application into. [default: 01:16:13 INFO - "/builds/slave/test"] 01:16:13 INFO - --app=APP Application being installed. [default: firefox] 01:16:13 INFO - mkdir: /builds/slave/test/build/application 01:16:13 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'] 01:16:13 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 01:16:27 INFO - Reading from file tmpfile_stdout 01:16:27 INFO - Output received: 01:16:27 INFO - /builds/slave/test/build/application/firefox/firefox 01:16:27 INFO - Running post-action listener: _resource_record_post_action 01:16:27 INFO - ##### 01:16:27 INFO - ##### Running run-tests step. 01:16:27 INFO - ##### 01:16:27 INFO - Running pre-action listener: _resource_record_pre_action 01:16:27 INFO - Running main action method: run_tests 01:16:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:16:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:16:27 INFO - Python 2.7.3 01:16:27 INFO - Return code: 0 01:16:27 INFO - grabbing minidump binary from tooltool 01:16:27 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')]} 01:16:27 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 0x1203220>, '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 0x116f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:16:27 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 01:16:27 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 01:16:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:16:27 INFO - Return code: 0 01:16:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:16:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:16:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:16:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:16:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:16:27 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/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test/build 01:16:27 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/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 01:16:27 INFO - Using env: {'DISPLAY': ':0', 01:16:27 INFO - 'HOME': '/home/cltbld', 01:16:27 INFO - 'LANG': 'en_US.UTF-8', 01:16:27 INFO - 'LANGUAGE': 'en_US:en', 01:16:27 INFO - 'LOGNAME': 'cltbld', 01:16:27 INFO - 'MAIL': '/var/mail/cltbld', 01:16:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:16:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:16:27 INFO - 'MOZ_NO_REMOTE': '1', 01:16:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:16:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:16:27 INFO - 'NO_EM_RESTART': '1', 01:16:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:16:27 INFO - 'PWD': '/builds/slave/test', 01:16:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:16:27 INFO - 'SHELL': '/bin/bash', 01:16:27 INFO - 'SHLVL': '1', 01:16:27 INFO - 'TERM': 'linux', 01:16:27 INFO - 'TMOUT': '86400', 01:16:27 INFO - 'USER': 'cltbld', 01:16:27 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563', 01:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:16:27 INFO - '_': '/tools/buildbot/bin/python'} 01:16:27 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/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 01:16:28 INFO - mozversion INFO | application_buildid: 20151124233732 01:16:28 INFO - mozversion INFO | application_changeset: 8233c8116679022ce8ac046186058b9dd691d50c 01:16:28 INFO - mozversion INFO | application_display_name: Nightly 01:16:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:16:28 INFO - mozversion INFO | application_name: Firefox 01:16:28 INFO - mozversion INFO | application_remotingname: firefox 01:16:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 01:16:28 INFO - mozversion INFO | application_vendor: Mozilla 01:16:28 INFO - mozversion INFO | application_version: 45.0a1 01:16:28 INFO - mozversion INFO | platform_buildid: 20151124233732 01:16:28 INFO - mozversion INFO | platform_changeset: 8233c8116679022ce8ac046186058b9dd691d50c 01:16:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 01:16:28 INFO - mozversion INFO | platform_version: 45.0a1 01:16:28 INFO - 2015-11-25 01:16:28,106 DEBUG : using testdate: 1448442988 01:16:28 INFO - 2015-11-25 01:16:28,107 DEBUG : actual date: 1448442988 01:16:28 INFO - 2015-11-25 01:16:28,155 INFO : starting webserver on 'localhost:44891' 01:16:28 INFO - 2015-11-25 01:16:28,156 INFO : Starting test suite talos-linux64-ix-035 01:16:28 INFO - 2015-11-25 01:16:28,156 INFO : Starting test dromaeo_css 01:16:28 INFO - 2015-11-25 01:16:28,156 DEBUG : operating with platform_type : linux_ 01:16:28 INFO - 2015-11-25 01:16:28,157 INFO : Initialising browser for dromaeo_css test... 01:16:28 INFO - 2015-11-25 01:16:28,173 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4tYxJY/profile http://localhost:44891/getInfo.html 01:16:32 INFO - 2015-11-25 01:16:32,210 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:16:32 INFO - 2015-11-25 01:16:32,210 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:16:32 INFO - 2015-11-25 01:16:32,210 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:16:32 INFO - 2015-11-25 01:16:32,210 DEBUG : BROWSER_OUTPUT: __metrics 01:16:32 INFO - 2015-11-25 01:16:32,331 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] 01:16:33 INFO - 2015-11-25 01:16:33,593 INFO : Browser initialized. 01:16:33 INFO - 2015-11-25 01:16:33,594 INFO : Running cycle 1/1 for dromaeo_css test... 01:16:33 INFO - 2015-11-25 01:16:33,594 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4tYxJY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:16:45 INFO - 2015-11-25 01:16:45,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 138805248 01:16:45 INFO - 2015-11-25 01:16:45,530 DEBUG : BROWSER_OUTPUT: 01:17:36 INFO - 2015-11-25 01:17:36,633 DEBUG : BROWSER_OUTPUT: 1448443056630 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] 01:19:48 INFO - 2015-11-25 01:19:48,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44891/tests/dromaeo/cssquery-ext.html) 01:19:49 INFO - 2015-11-25 01:19:49,600 DEBUG : BROWSER_OUTPUT: RSS: Main: 234926080 01:19:49 INFO - 2015-11-25 01:19:49,600 DEBUG : BROWSER_OUTPUT: 01:22:52 INFO - 2015-11-25 01:22:52,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-ext.html (next: http://localhost:44891/tests/dromaeo/cssquery-jquery.html) 01:22:52 INFO - 2015-11-25 01:22:52,712 DEBUG : BROWSER_OUTPUT: RSS: Main: 221810688 01:22:52 INFO - 2015-11-25 01:22:52,712 DEBUG : BROWSER_OUTPUT: 01:25:54 INFO - 2015-11-25 01:25:54,791 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44891/tests/dromaeo/cssquery-mootools.html) 01:25:55 INFO - 2015-11-25 01:25:55,373 DEBUG : BROWSER_OUTPUT: RSS: Main: 216567808 01:25:55 INFO - 2015-11-25 01:25:55,373 DEBUG : BROWSER_OUTPUT: 01:28:57 INFO - 2015-11-25 01:28:57,409 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44891/tests/dromaeo/cssquery-prototype.html) 01:28:57 INFO - 2015-11-25 01:28:57,997 DEBUG : BROWSER_OUTPUT: RSS: Main: 204247040 01:28:57 INFO - 2015-11-25 01:28:57,997 DEBUG : BROWSER_OUTPUT: 01:32:00 INFO - 2015-11-25 01:32:00,870 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44891/tests/dromaeo/cssquery-yui.html) 01:32:01 INFO - 2015-11-25 01:32:01,471 DEBUG : BROWSER_OUTPUT: RSS: Main: 204087296 01:32:01 INFO - 2015-11-25 01:32:01,471 DEBUG : BROWSER_OUTPUT: 01:35:03 INFO - 2015-11-25 01:35:03,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/dromaeo/cssquery-yui.html (next: http://localhost:44891/tests/dromaeo/cssquery-dojo.html) 01:35:04 INFO - 2015-11-25 01:35:04,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 222261248 01:35:04 INFO - 2015-11-25 01:35:04,223 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,224 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:35:04 INFO - 2015-11-25 01:35:04,224 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:35:04 INFO - 2015-11-25 01:35:04,224 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:35:04 INFO - 2015-11-25 01:35:04,224 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:35:04 INFO - 2015-11-25 01:35:04,224 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2577.875399361022;2703.428520275714;2714.457108578285;2715.253305341032;2727.972720272797;4474.843380481684;4607.101615185032;4621.58405743483;4637.258038713802;4651.953480465194;4484.529124441936;4554.043650833329;4558.1567410029165;4595.88510287243;4640.651951103664;4296.205202037633;4350.825966961318;4376.759278239696;4378.912421751563;4393.209222339978;4451.821927122911;4482.686211965148;4505.36924830524;4511.345854851044;4525.389072475223;4218.303979843322;4247.68142389322;4298.591633794785;4308.073764140725;4340.65274778017;4345.109252603209;4345.13097380522;4348.130373925196;4353.216421044211;4390.736555806662;4325.5025672047805;4330.675199360061;4343.478782546114;4353.5428779977965;4389.2977123660385;4406.317020861771;4526.162659907927;4535.070310586322;4544.795484203219;4618.65360097994;4971.279164521125;5020.297158397827;5023.32185155003;5026.145555255615;5070.2141168119;5044.747762611854;5048.747562621891;5052.343195384613;5053.216751403538;5057.241413787961;4519.276915693474;4603.49361570227;4633.212353899845;4636.559526844944;4702;5095.592352611782;5120.359955005624;5121.9743901281;5123.205903085028;5131.050755610224;4906.705597664151;4924.704517728947;4925.088858561177;4926.33494478248;4964.726940018261;4809.975950120227;4911.705297682151;4914.287428322873;4929.679570827885;4990.401151861727;4947.5794557462295;4951.034548263054;4954.1825598775795;4956.677815942025;4967;4919.926201106986;4945.035718034948;4949.232868905326;4950.653454258239;4984.975075124601;4030.7380020298588;4068.6541643960595;4108.568600296986;4162.521310049323;4199.895002624959;3576.3383774001372;3629.1834337273904;3644.1800594865945;3645.8359373828243;3670.871519496805;4303.892402689958;4311.9784401077795;4317.481902171697;4317.589828966243;4355.716878402894;4815.3258543803895;4915.926261106087;5036.6726162799305;5081.28861959326;5083.923741143886;4235.53409124996;4240.300350442142;4274.935875961863;4294.098239369705;4335.6965012449455;4413.558644135561;4417.116576684676;4438.822447102144;4457.04173602677;4495.685302028826;4497.145542346943;4625.699329543569;4704.858854234379;4705.529447055335;4714.127886340969;4864.100141473838;4931.7040977541465;4957.305977163128;4970.155073637563;4982.701037937735;4753.310769938271;4773.451053128796;4789.185838407409;4796.808127674994;4831.323614694016;4958.057968985881;4991.301217829434;4991.875203119951;4993.425756038102;5044.848654540369;4722.36248106515;4821.686590371614;4827.034593081327;4867.70793752376;4941.234108713155;4882.755862206946;4899.167141585867;4902.8774280643265;4916.336294600326;4962.206047032458;4867.70793752376;4907.705537667752;4914.631402644887;4922.483139270325;4963.205887058053;4537.092581483783;4634.513376095461;4634.976825115853;4638;4748.62011039123;4882.340883980617;4935.679180853233;4939.827106051271;4941.184704523714;4992.201247800334;4823.758812059453;4832.226843704991;4837.274408838562;4838.516148385274;4882.658213924991 01:35:04 INFO - 2015-11-25 01:35:04,226 DEBUG : BROWSER_OUTPUT: |1;ext.html;546.2625455634893;554.7337278106509;558.8323502949114;569.8803251317223;573.5125143627916;28077.157685269423;28675.713242867565;28704.13887583373;35906.461403078974;36804;39220.411693824615;39741.205175896466;39812.40281395774;39849.60150398495;39866.80066599663;7827.647755850972;7990.280874721274;7991.840163196733;7995;7998.840023199533;7108.85782284354;7321.084864391951;10160.390376577392;10416.166706663449;10454.059134677878;10122.443265620388;10222.233332500093;10239.641612543563;10240.948795256014;10245.743856403575;7120.1099862517185;7325.267473252632;10026.69919902404;10031.147352475049;10139.138173255282;10136.493175341204;10369.066783989478;10370.792584148276;10370.896291037068;10374.896251037468;37127.62872371296;37629.811850940845;37657.62342376568;37704.24591508154;37778.24443511142;2837.4041451295047;2901.970980290212;2929;3175.301433684586;3448.758586898918;28353.73259370529;28839.855800720863;28935;28951.710482895112;29394.412111757858;3371.578552680915;3417.709494692954;3428.468587368962;3503.509508668763;3548.503209550665;230478;230791;231266;232575;232650;49908.50091499111;51396.22905656418;54802.45197548053;55247.17129243065;55329.1700624491;56909.715451422475;58435.95474158469;58485;58539;58539.70730146322;12887.16233444823;13103.803442948363;13106.934465327613;13109.147905386119;13109.344532773322;9781.608735650636;9816.165625921734;9829.55766990487;9829.901700982899;9840.868300145432;17252;17433.47699569015;17872.463826085237;18036;18093.909530452525;6742.49431292655;6962;7035.683394247271;7109.28907109285;7123.89314160288;800.3237264511479;815.9388045896582;903.9186473217442;971.2230215827451;1001.534689972688;26438.735612644014;26531.86734066356;26803.731962680515;26883.462330753275;26930.596041059398;928.7863791328033;955.8852937647388;956.2015716876347;964.8600952861796;972.0910948263255;13830;13906.652333691738;13919.791203131961;14024.579262622137;14105.224212668401;262860;264011;266054;266800;267013;36426;45432.3185152223;45873.311900321525;45894;45935.31097033547;1056.3978532236706;1104.7569534702513;1168.065547561964;1197.2995797458411;1198.4367347346733;23756.52486950286;23952.76047239575;24430.389240269134;24444.51110977806;24444.75555244496;33504.16239594029;33850.49224261589;34912.825435872655;35628;40926.386104207864;164427;165279.1736041312;166183;166596;166764;26256.73743262543;26744.331391715365;26763.06329278466;26964.05625803166;26975.730242697322;37085.62914370822;37955.62044379521;38398.80800595979;39083.2183356337;39531;30050.982527346732;31310;31817.204569885937;32313.15302704519;32481.187970300933;31464.685353146175;31659.366812664077;31869.840650797527;31898.681013189573;32322;32095;32698.34603307873;32858.671413286516;32900.1774955628;32949.670503295616;28877.566836497048;28950.42099158047;29445.411091778475;29486.262843429085;29534.409311814074;22914.770852291265;23379.88310058439;23387;23425.297241082793;23535.646965295193 01:35:04 INFO - 2015-11-25 01:35:04,227 DEBUG : BROWSER_OUTPUT: |2;jquery.html;339.78763272954404;343.1164750766775;344.4373858492814;345.751059237349;345.8288147367054;12071.75856482872;12534.81197782034;15025.248737563119;15130.773038404419;15931.442399516034;12148.651009558975;13856.515021974234;14778.187199704013;14964;15007.249637518134;4956.504349565051;5093.465186155456;5100.77046532905;5100.872478188038;5101.15830887903;3626.057225121461;3743.3449146399407;5058.848234552969;5100.081985242673;5102.795888164469;3705.6479634434822;3718.3307004739136;3721.0883333583174;3722.1066943933397;5479.726013699319;5627.8593035174035;5750.712464376807;5769.509591684712;5829.213056237396;5831.854203644922;4994.35073440454;4996.200607902754;4998.725070121178;5002.049610574016;5019.924701129486;19212;20152.29466968664;20387.796122038737;20419.387418377322;21077.051532680915;1757.1038770227149;1794.676958147533;1846.021608547472;1924.951876203092;1986.9105890234973;12314.876851231462;12672.61982140528;12931.383577052868;13256.270905100215;13962.301884905815;1804.4496428589275;1867.598466329732;1877.6714075036814;1885.2930151193302;1907.1989764299028;60460.395396046355;61010.69494652543;62598.37401626017;66420;67262;15396.84603153954;15548.301896195197;15625.921870390803;15656.13891235993;15950.920245398698;22866.42833929165;22976;23250.53498930012;23257.88371058134;23263.651045234335;8330.125336839666;8335.333173346118;8377.078521362646;8418.915810841936;8438.831223375499;7010.684519196649;7066.50534462593;7134.892976605355;7147.964260178665;7156.534825236343;7569.394448444222;7599.696012159562;7624.961875190588;7672.501287416411;7826.8434631307055;1747.6941535231283;1779.2082523277277;2199.82401407887;2833.192540126054;2941.3087924337765;784.8077221080872;797.2625321577517;821.8767184922309;838.7190291252499;970.9174720148867;15086;15517.5344739658;15808.051516909023;15872;16089.678206435805;842.5997651115671;870.6822009966317;894.6376717429451;931.1200915135132;941.755143662639;7485.4385921055045;8560.443571167854;8885.200331970154;9164.587593558306;9318;475187.7443676695;482513;482614;485562;489295;69406.65296673484;70266;70391.64804176049;71227.64386178037;72123;997.0926456924019;1003.1423133221136;1086.5165001574383;1107.0534692837668;1107.1530279336152;9661.855072174063;9720.416774993522;14563.708725825636;14590.781138283146;14640.853591464374;31539.52690709595;32380.35239295248;32732.836335818167;33037.66962330442;35084.947451576496;45731.77134114308;46897.53102469068;48376.30682926081;48790.75604621954;49319.260211096145;13938.372773225432;13949.362925608932;14021.439142434188;14063.507777227747;14240.928795355956;13150.671233219246;17473.82526174722;17504.649907002044;17971.550711232325;18174.273029079217;11896.405179741152;13761.518346857813;14406.711865762416;14417.855821441652;14420.711585768435;13681.726365472834;13704.177749335071;14220.715585688435;14300;14477.565673029825;13962.441502339792;14064.367103480572;14146.151230926178;14185.432582696989;14376.496822611158;12677.17598356104;13005;13266.33668316561;13395;13401.92784577251;13906.930465347608;14900.776488352467;14922.552323430315;15248;15278.694426111193 01:35:04 INFO - 2015-11-25 01:35:04,228 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1375.0993099519817;1573.4807513520539;1595.944141955032;1603.1903888536287;1642.039406946936;5546.500814926656;6605.27341992381;6824.82937926552;6851.109355783754;6869.038334633149;6772.424343930759;6895.862082758342;6923.792286231421;6928.237893831687;6930.965345173267;1735.8264173582668;1760.8943463392172;2018.3944816555047;2078.9480262993393;2123.6389813731703;2059.1969132038516;2060.9587808243828;2064.9793502064936;2065.6488396972477;2066.380085974209;2008.116428771336;2009.6784514477613;2066.8036536529007;2070.8343332533436;2071.078370125295;2021.3935819254166;2067.503799088223;2068;2105.221068204766;2105.589410065038;2105.231590469483;2125.4686328417897;2131.8507704460694;2133.1040962795664;2134.210342173406;6678.232003319632;6755.864882702367;6757.628330441824;6779.32206779323;6784.118064651613;4118.135191609766;4182.853600124012;4193.853215137486;4199.265128602482;4226.682998775104;2257.616205245096;2692.6634170728657;2798.720127987205;2812.8874845006176;2828.533292006837;794.6702118620766;829.0465964141285;899.2401420799414;939.286142531674;948.7675519497698;9203.401778884418;9211.953940230323;9238.676646317414;9245.815083698288;9255.352125351228;3863.150106976461;3932.115274063313;3933.7049721270446;3952.6047395260243;3978.7214894957074;7890.171531989174;7898.644560994769;7900.920990790019;7907;7918.168592297841;4171.478565179353;4188.6230239278;4200.28595138821;4202.726822756511;4204.495460544754;3805.5813860475328;3839.3665045267217;3840.8271627776817;3843.0200298924156;3845.9230815383535;2744.3276397282793;2768.6123942648046;3092.6443459002053;3121.4392803598203;3340.8329583520726;1065.0840277361463;1079.6275285026654;1083.6586475260267;1090.9509072091776;1092.3773449134076;813.5403497024163;817.1379194949267;817.3052905030795;870.1124852650262;914.6478605736839;6382.170317858741;6441.130447389636;6459.483241340681;6480.189976252968;6486.44865186455;1004.3070281505884;1008.4554340655964;1027.0653705128298;1030.701096681969;1049.968500944973;5023.34696489454;5050.191969284897;5059.418166910852;5069.7465126744255;5084;8052.758417247492;8119.269265765991;8185.71350002747;8191.754247372588;8330.083690794008;8110.391720620977;8158.4289099762445;8165.101838797727;8178.713745018896;8217.95891020553;6290.779822706183;6335.049742538564;6375.649339286291;6385.744570217233;6401.295857455676;5885.61743486672;6105.5420843437805;6117.480014198665;6137;6138.324784273637;7055.259197784364;7113.822154445973;7174.892376614459;7307.707691692485;7500.174980752004;7425.480216385017;7544.509606875755;7594.582297973665;7662.310392064741;7681.462297639335;6046.848828779316;6117.143599896107;6127.019676851861;6134.570580059353;6136.815895523141;5453.972730136483;5686.800961966312;5698.886022279448;6005.429484198994;6342.936570634234;6060.151578778887;6062.575619706755;6088.969555152196;6136.2636483622255;6199.318075011655;5875.471207591167;5954;5971.373005834499;5995.340512543704;6064.545159113172;6119.541034422347;6121.84695382619;6129.754809807558;6199.752009919733;6203.472704820141;5674.460926212003;5702.258706368146;5703.20155178267;5703.372629010722;5753.827385178452;5644.9435505644415;5655.208270842168;5660.037793575019;5673.290838645169;5681.687507186979 01:35:04 INFO - 2015-11-25 01:35:04,228 DEBUG : BROWSER_OUTPUT: |4;prototype.html;309.89153796171337;314.41361860130866;317.1120861587554;319.7074676670847;320.13244108466057;5066.341375621173;5105.719185444804;5249.186376111699;5314.202869569566;5329.360476742787;5044.747762611873;5148.948510514893;5167.069927413064;5202.3497062867145;5203.531682148606;3897.2400381925536;4012.2577323195155;4156.875293741177;4160.500739911215;4169.937450938239;4076.6331030207275;4178.958210417887;4188.97905510472;4196.076863090116;4221.873343799691;4120.567340429257;4127.752334859903;4135.648469880049;4138.4413104231;4139.296319625671;4121.361189015707;4150.667946564267;4154.709170358076;4164.083901541671;4185.58144185582;3692.5753538343165;3705.7776533408082;3708.592054873962;3708.9258214835554;3709.239605880788;4885.5358740919555;5024.196128619404;5031.06925218832;5069.569086627641;5085.949140508622;4795.736234507101;4808.591269742076;4810.628970743356;4811.4707382187935;4811.542903424169;5307.230451584537;5448.727563621803;5612.186232996192;5622.887542249173;5633.464820842013;3901.9219615607813;3995.420664003703;4004.599540046001;4027.939580906289;4029.738067025634;5748.856278593069;5794.536437085023;5841.357450680422;5842.795502157404;5843.415658434165;4118.855840045584;4140.585941405896;4151.3772934059525;4155.293600087993;4156.04410985469;4080.2451546464;4197.937030944478;4204.894877628084;4208.768517731554;4247.17180149874;4734.573888350065;4760.476347601761;4772.0694464579765;4773.618110551217;4785.545373189541;5579.302587176603;5597.74810133545;5606.6916319602;5608.074667679787;5616.803411880564;1474.9262536873114;1498.4980031689506;1499.6850661361018;1500.2123884960483;1500.48983345663;1034.7930413917247;1036.4092467293722;1037.2108113504296;1038.8597539332245;1060.4644654449453;4818.036392721469;4821.325014497973;4822.276658501182;4822.493638168012;4825.372701548777;5700.9429905701245;5703.800366987136;5705;5712.143178523254;5714.714264286769;4817.614590832795;4827.034593081327;4828.155072862235;4831.613470922318;4832.637552183601;3010.1722026442903;3081.659478127022;3093.211231136046;3115.501519756873;3129.2802655389387;5492.642978206483;5507.779688812483;5514.6139770216505;5514.751836167382;5516.668999860021;5976.581639285295;5982.611130276605;6002.459778619946;6012.338642749294;6019.277686677627;5480.561555075505;5490.203920431515;5490.890182196334;5491.121420572769;5504.4770746778995;5575.9163612545035;5672.971635141798;5736.71316434185;5833.270841144857;5843.883122337445;5598.86002849932;5693.259876216065;5706.343770466284;5714.3714191438075;5722.6852523109965;5481.232627432083;5482.725863706719;5487.451254874481;5489.066858634122;5492.890142197213;3977.9005524860954;4015.0765323975065;4023.8390464381096;4026.476558047435;4031.254217969685;5644.8024319148635;5808.983427900185;5819.214406055297;5822.097574875901;5832.679202643895;5609.27079479665;5614.9719251403485;5616.747246373841;5618.747156377891;5622.606417550732;5603.831885043455;5612.242347283064;5614.550835933034;5615.241942337732;5616.69108199053;5313.574914006947;5490.890182196254;5491.917621235764;5499.312585926759;5515.034868898007;5613.859653508695;5651.943480565142;5656.2929633795775;5662.745176467151;5666.263385759825;5514.91727624078;5532.529734972572;5533;5535.916961245504;5539.944600553943 01:35:04 INFO - 2015-11-25 01:35:04,229 DEBUG : BROWSER_OUTPUT: |5;yui.html;626.4424662050773;690.7168061094949;701.2181417719243;756.7389250708503;772.5210369570865;11553.711157221074;11670.41647917605;11692.415379231037;11710.121740869427;11717.0626349892;11511.079113670907;11637.709057273572;11703.239289446181;11757.176997610171;11829.7634047319;613.1171113596429;621.5276389943652;766.7699690092978;783.8706613408781;785.7524879662916;773.5126870071847;783.2832957843566;789.6328207383566;789.8933643958078;790.7034861926777;814.7352110564091;822.7079386817684;823.1685997142899;824.2128767027474;824.505296821908;629.6725702634627;638.4764493115647;801.6793282686914;809.8947136872227;818.758466252457;605.7032054293364;605.7365046204915;605.8000859716283;605.9242594675666;606.2664176346626;11264.21150519464;11365.374904380256;11424.257423267461;11463.312201267867;11533.250338727956;11536.596219132374;11637.941810290895;11850.288982660983;12055.517779288817;12169.330686812222;9270.165685088306;9404.167598042828;9523.380980236263;9540.379875308083;9551.235901127893;3330.7834990725523;3355.228297491566;3383.881564145268;3391.084407210064;3391.135260508579;42107.57892421067;42405.57594424078;42509.36235956463;42994;43087.784561076995;8939.240164586083;9035.412698174729;9171.266298696088;9180.265578753684;9250.4912229828;16257.918710406373;16410.917945410434;16419.1790410479;16437.753433698505;16447.588810279838;6211;6334.239891213084;6363.33185015576;6366.681665916778;6443.194600674916;3523.1544429337287;3578.7137029037613;3594.4967704521387;3686.9262614747554;3689.7970611616083;2751.7385848344375;2789.4002789400374;2807.6350074490188;2820.2244382794483;2823.280063583814;499.29599265036126;500.1213165569915;505.19673718787305;506.44291279592153;507.93650793650795;408.9427480152781;426.1753506963973;457.4898987628836;514.8043743377492;538.0798833993836;7389.261073892568;7491.625418729151;7498.400127989857;7608.125065617414;7640.80897977555;476.5353780064479;512.3467578837012;546.9015577196063;549.0748089469178;564.9604527683023;3600.135967367813;3613.3282404122842;3689.1699367642605;3701.9629803702155;3725.6088110748524;49695.25457118146;50634;50663.7466812671;50668.49331506638;50806.23790643143;43547.78226108849;43593.56406435895;43789.78105109454;44251.33622995658;44351.11297774027;41758.791206043774;41988.37017444741;42398.15203695847;42473.36289955653;42513.57486425158;7726.7681969541;7805.492642978188;7955.473906778251;7984.960075199587;8137.186281371815;31260;31279.53080703837;31851.68148318487;31975.520367194047;32197.67802321947;37609.623903760614;42911.78544107259;43231;43613.56386436095;44527;9469.952650236704;9663.371880828005;9911.65309214174;9921.156701680438;9962.900370996198;10357.689269321932;10493.108085812792;10535.473226338805;10536.578536858746;10879.238453308193;9956.601735930772;10012.749681258027;10076.244281678757;10157.136643385395;10286.434246116536;10099.94950025245;10167.084962353423;10191.694249172537;10246.948765256126;10357.274990750575;9883.258755593502;9898;10127.139193168663;10176.389416635026;10402.531886064993;8797.648094076165;9134.314926380415;9161.86257206129;9251.121143491358;9363.578638961399;8253.917460825314;8371.455855369382;8524.701635442978;8711.738647840575;9159.86260206084 01:35:04 INFO - 2015-11-25 01:35:04,229 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,889 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: __startTimestamp1448444104222__endTimestamp 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: Number of tests: 6 01:35:04 INFO - 2015-11-25 01:35:04,230 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,231 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4606.46 Median:4705.53 stddev:474.80 (10.1%) stddev-sans-first:448.79 01:35:04 INFO - 2015-11-25 01:35:04,231 DEBUG : BROWSER_OUTPUT: Values: 2577.9 2703.4 2714.5 2715.3 2728.0 4474.8 4607.1 4621.6 4637.3 4652.0 4484.5 4554.0 4558.2 4595.9 4640.7 4296.2 4350.8 4376.8 4378.9 4393.2 4451.8 4482.7 4505.4 4511.3 4525.4 4218.3 4247.7 4298.6 4308.1 4340.7 4345.1 4345.1 4348.1 4353.2 4390.7 4325.5 4330.7 4343.5 4353.5 4389.3 4406.3 4526.2 4535.1 4544.8 4618.7 4971.3 5020.3 5023.3 5026.1 5070.2 5044.7 5048.7 5052.3 5053.2 5057.2 4519.3 4603.5 4633.2 4636.6 4702.0 5095.6 5120.4 5122.0 5123.2 5131.1 4906.7 4924.7 4925.1 4926.3 4964.7 4810.0 4911.7 4914.3 4929.7 4990.4 4947.6 4951.0 4954.2 4956.7 4967.0 4919.9 4945.0 4949.2 4950.7 4985.0 4030.7 4068.7 4108.6 4162.5 4199.9 3576.3 3629.2 3644.2 3645.8 3670.9 4303.9 4312.0 4317.5 4317.6 4355.7 4815.3 4915.9 5036.7 5081.3 5083.9 4235.5 4240.3 4274.9 4294.1 4335.7 4413.6 4417.1 4438.8 4457.0 4495.7 4497.1 4625.7 4704.9 4705.5 4714.1 4864.1 4931.7 4957.3 4970.2 4982.7 4753.3 4773.5 4789.2 4796.8 4831.3 4958.1 4991.3 4991.9 4993.4 5044.8 4722.4 4821.7 4827.0 4867.7 4941.2 4882.8 4899.2 4902.9 4916.3 4962.2 4867.7 4907.7 4914.6 4922.5 4963.2 4537.1 4634.5 4635.0 4638.0 4748.6 4882.3 4935.7 4939.8 4941.2 4992.2 4823.8 4832.2 4837.3 4838.5 4882.7 01:35:04 INFO - 2015-11-25 01:35:04,231 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,231 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38186.54 Median:31562.03 stddev:58522.02 (185.4%) stddev-sans-first:58617.94 01:35:04 INFO - 2015-11-25 01:35:04,232 DEBUG : BROWSER_OUTPUT: Values: 546.3 554.7 558.8 569.9 573.5 28077.2 28675.7 28704.1 35906.5 36804.0 39220.4 39741.2 39812.4 39849.6 39866.8 7827.6 7990.3 7991.8 7995.0 7998.8 7108.9 7321.1 10160.4 10416.2 10454.1 10122.4 10222.2 10239.6 10240.9 10245.7 7120.1 7325.3 10026.7 10031.1 10139.1 10136.5 10369.1 10370.8 10370.9 10374.9 37127.6 37629.8 37657.6 37704.2 37778.2 2837.4 2902.0 2929.0 3175.3 3448.8 28353.7 28839.9 28935.0 28951.7 29394.4 3371.6 3417.7 3428.5 3503.5 3548.5 230478.0 230791.0 231266.0 232575.0 232650.0 49908.5 51396.2 54802.5 55247.2 55329.2 56909.7 58436.0 58485.0 58539.0 58539.7 12887.2 13103.8 13106.9 13109.1 13109.3 9781.6 9816.2 9829.6 9829.9 9840.9 17252.0 17433.5 17872.5 18036.0 18093.9 6742.5 6962.0 7035.7 7109.3 7123.9 800.3 815.9 903.9 971.2 1001.5 26438.7 26531.9 26803.7 26883.5 26930.6 928.8 955.9 956.2 964.9 972.1 13830.0 13906.7 13919.8 14024.6 14105.2 262860.0 264011.0 266054.0 266800.0 267013.0 36426.0 45432.3 45873.3 45894.0 45935.3 1056.4 1104.8 1168.1 1197.3 1198.4 23756.5 23952.8 24430.4 24444.5 24444.8 33504.2 33850.5 34912.8 35628.0 40926.4 164427.0 165279.2 166183.0 166596.0 166764.0 26256.7 26744.3 26763.1 26964.1 26975.7 37085.6 37955.6 38398.8 39083.2 39531.0 30051.0 31310.0 31817.2 32313.2 32481.2 31464.7 31659.4 31869.8 31898.7 32322.0 32095.0 32698.3 32858.7 32900.2 32949.7 28877.6 28950.4 29445.4 29486.3 29534.4 22914.8 23379.9 23387.0 23425.3 23535.6 01:35:04 INFO - 2015-11-25 01:35:04,232 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,233 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27583.68 Median:2887.25 stddev:78819.36 (2729.9%) stddev-sans-first:79013.92 01:35:04 INFO - 2015-11-25 01:35:04,233 DEBUG : BROWSER_OUTPUT: Values: 339.8 343.1 344.4 345.8 345.8 12071.8 12534.8 15025.2 15130.8 15931.4 12148.7 13856.5 14778.2 14964.0 15007.2 4956.5 5093.5 5100.8 5100.9 5101.2 3626.1 3743.3 5058.8 5100.1 5102.8 3705.6 3718.3 3721.1 3722.1 5479.7 5627.9 5750.7 5769.5 5829.2 5831.9 4994.4 4996.2 4998.7 5002.0 5019.9 19212.0 20152.3 20387.8 20419.4 21077.1 1757.1 1794.7 1846.0 1925.0 1986.9 12314.9 12672.6 12931.4 13256.3 13962.3 1804.4 1867.6 1877.7 1885.3 1907.2 60460.4 61010.7 62598.4 66420.0 67262.0 15396.8 15548.3 15625.9 15656.1 15950.9 22866.4 22976.0 23250.5 23257.9 23263.7 8330.1 8335.3 8377.1 8418.9 8438.8 7010.7 7066.5 7134.9 7148.0 7156.5 7569.4 7599.7 7625.0 7672.5 7826.8 1747.7 1779.2 2199.8 2833.2 2941.3 784.8 797.3 821.9 838.7 970.9 15086.0 15517.5 15808.1 15872.0 16089.7 842.6 870.7 894.6 931.1 941.8 7485.4 8560.4 8885.2 9164.6 9318.0 475187.7 482513.0 482614.0 485562.0 489295.0 69406.7 70266.0 70391.6 71227.6 72123.0 997.1 1003.1 1086.5 1107.1 1107.2 9661.9 9720.4 14563.7 14590.8 14640.9 31539.5 32380.4 32732.8 33037.7 35084.9 45731.8 46897.5 48376.3 48790.8 49319.3 13938.4 13949.4 14021.4 14063.5 14240.9 13150.7 17473.8 17504.6 17971.6 18174.3 11896.4 13761.5 14406.7 14417.9 14420.7 13681.7 13704.2 14220.7 14300.0 14477.6 13962.4 14064.4 14146.2 14185.4 14376.5 12677.2 13005.0 13266.3 13395.0 13401.9 13906.9 14900.8 14922.6 15248.0 15278.7 01:35:04 INFO - 2015-11-25 01:35:04,233 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,233 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4762.47 Median:5919.81 stddev:2427.74 (41.0%) stddev-sans-first:2421.20 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: Values: 1375.1 1573.5 1595.9 1603.2 1642.0 5546.5 6605.3 6824.8 6851.1 6869.0 6772.4 6895.9 6923.8 6928.2 6931.0 1735.8 1760.9 2018.4 2078.9 2123.6 2059.2 2061.0 2065.0 2065.6 2066.4 2008.1 2009.7 2066.8 2070.8 2071.1 2021.4 2067.5 2068.0 2105.2 2105.6 2105.2 2125.5 2131.9 2133.1 2134.2 6678.2 6755.9 6757.6 6779.3 6784.1 4118.1 4182.9 4193.9 4199.3 4226.7 2257.6 2692.7 2798.7 2812.9 2828.5 794.7 829.0 899.2 939.3 948.8 9203.4 9212.0 9238.7 9245.8 9255.4 3863.2 3932.1 3933.7 3952.6 3978.7 7890.2 7898.6 7900.9 7907.0 7918.2 4171.5 4188.6 4200.3 4202.7 4204.5 3805.6 3839.4 3840.8 3843.0 3845.9 2744.3 2768.6 3092.6 3121.4 3340.8 1065.1 1079.6 1083.7 1091.0 1092.4 813.5 817.1 817.3 870.1 914.6 6382.2 6441.1 6459.5 6480.2 6486.4 1004.3 1008.5 1027.1 1030.7 1050.0 5023.3 5050.2 5059.4 5069.7 5084.0 8052.8 8119.3 8185.7 8191.8 8330.1 8110.4 8158.4 8165.1 8178.7 8218.0 6290.8 6335.0 6375.6 6385.7 6401.3 5885.6 6105.5 6117.5 6137.0 6138.3 7055.3 7113.8 7174.9 7307.7 7500.2 7425.5 7544.5 7594.6 7662.3 7681.5 6046.8 6117.1 6127.0 6134.6 6136.8 5454.0 5686.8 5698.9 6005.4 6342.9 6060.2 6062.6 6089.0 6136.3 6199.3 5875.5 5954.0 5971.4 5995.3 6064.5 6119.5 6121.8 6129.8 6199.8 6203.5 5674.5 5702.3 5703.2 5703.4 5753.8 5644.9 5655.2 5660.0 5673.3 5681.7 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4648.38 Median:5077.76 stddev:1340.22 (26.4%) stddev-sans-first:1303.82 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: Values: 309.9 314.4 317.1 319.7 320.1 5066.3 5105.7 5249.2 5314.2 5329.4 5044.7 5148.9 5167.1 5202.3 5203.5 3897.2 4012.3 4156.9 4160.5 4169.9 4076.6 4179.0 4189.0 4196.1 4221.9 4120.6 4127.8 4135.6 4138.4 4139.3 4121.4 4150.7 4154.7 4164.1 4185.6 3692.6 3705.8 3708.6 3708.9 3709.2 4885.5 5024.2 5031.1 5069.6 5085.9 4795.7 4808.6 4810.6 4811.5 4811.5 5307.2 5448.7 5612.2 5622.9 5633.5 3901.9 3995.4 4004.6 4027.9 4029.7 5748.9 5794.5 5841.4 5842.8 5843.4 4118.9 4140.6 4151.4 4155.3 4156.0 4080.2 4197.9 4204.9 4208.8 4247.2 4734.6 4760.5 4772.1 4773.6 4785.5 5579.3 5597.7 5606.7 5608.1 5616.8 1474.9 1498.5 1499.7 1500.2 1500.5 1034.8 1036.4 1037.2 1038.9 1060.5 4818.0 4821.3 4822.3 4822.5 4825.4 5700.9 5703.8 5705.0 5712.1 5714.7 4817.6 4827.0 4828.2 4831.6 4832.6 3010.2 3081.7 3093.2 3115.5 3129.3 5492.6 5507.8 5514.6 5514.8 5516.7 5976.6 5982.6 6002.5 6012.3 6019.3 5480.6 5490.2 5490.9 5491.1 5504.5 5575.9 5673.0 5736.7 5833.3 5843.9 5598.9 5693.3 5706.3 5714.4 5722.7 5481.2 5482.7 5487.5 5489.1 5492.9 3977.9 4015.1 4023.8 4026.5 4031.3 5644.8 5809.0 5819.2 5822.1 5832.7 5609.3 5615.0 5616.7 5618.7 5622.6 5603.8 5612.2 5614.6 5615.2 5616.7 5313.6 5490.9 5491.9 5499.3 5515.0 5613.9 5651.9 5656.3 5662.7 5666.3 5514.9 5532.5 5533.0 5535.9 5539.9 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12348.44 Median:25085.89 stddev:14243.90 (56.8%) stddev-sans-first:14256.65 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: Values: 626.4 690.7 701.2 756.7 772.5 11553.7 11670.4 11692.4 11710.1 11717.1 11511.1 11637.7 11703.2 11757.2 11829.8 613.1 621.5 766.8 783.9 785.8 773.5 783.3 789.6 789.9 790.7 814.7 822.7 823.2 824.2 824.5 629.7 638.5 801.7 809.9 818.8 605.7 605.7 605.8 605.9 606.3 11264.2 11365.4 11424.3 11463.3 11533.3 11536.6 11637.9 11850.3 12055.5 12169.3 9270.2 9404.2 9523.4 9540.4 9551.2 3330.8 3355.2 3383.9 3391.1 3391.1 42107.6 42405.6 42509.4 42994.0 43087.8 8939.2 9035.4 9171.3 9180.3 9250.5 16257.9 16410.9 16419.2 16437.8 16447.6 6211.0 6334.2 6363.3 6366.7 6443.2 3523.2 3578.7 3594.5 3686.9 3689.8 2751.7 2789.4 2807.6 2820.2 2823.3 499.3 500.1 505.2 506.4 507.9 408.9 426.2 457.5 514.8 538.1 7389.3 7491.6 7498.4 7608.1 7640.8 476.5 512.3 546.9 549.1 565.0 3600.1 3613.3 3689.2 3702.0 3725.6 49695.3 50634.0 50663.7 50668.5 50806.2 43547.8 43593.6 43789.8 44251.3 44351.1 41758.8 41988.4 42398.2 42473.4 42513.6 7726.8 7805.5 7955.5 7985.0 8137.2 31260.0 31279.5 31851.7 31975.5 32197.7 37609.6 42911.8 43231.0 43613.6 44527.0 9470.0 9663.4 9911.7 9921.2 9962.9 10357.7 10493.1 10535.5 10536.6 10879.2 9956.6 10012.7 10076.2 10157.1 10286.4 10099.9 10167.1 10191.7 10246.9 10357.3 9883.3 9898.0 10127.1 10176.4 10402.5 8797.6 9134.3 9161.9 9251.1 9363.6 8253.9 8371.5 8524.7 8711.7 9159.9 01:35:04 INFO - 2015-11-25 01:35:04,235 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:35:04 INFO - 2015-11-25 01:35:04,236 DEBUG : BROWSER_OUTPUT: 01:35:04 INFO - 2015-11-25 01:35:04,969 INFO : Browser exited with error code: 0 01:35:04 INFO - 2015-11-25 01:35:04,987 INFO : Completed test dromaeo_css (00:18:36) 01:35:04 INFO - 2015-11-25 01:35:04,988 INFO : Starting test kraken 01:35:04 INFO - 2015-11-25 01:35:04,988 DEBUG : operating with platform_type : linux_ 01:35:04 INFO - 2015-11-25 01:35:04,988 INFO : Initialising browser for kraken test... 01:35:04 INFO - 2015-11-25 01:35:04,994 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoSWjf7/profile http://localhost:44891/getInfo.html 01:35:08 INFO - 2015-11-25 01:35:08,977 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:35:08 INFO - 2015-11-25 01:35:08,977 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:35:08 INFO - 2015-11-25 01:35:08,977 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:35:08 INFO - 2015-11-25 01:35:08,977 DEBUG : BROWSER_OUTPUT: __metrics 01:35:09 INFO - 2015-11-25 01:35:09,098 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] 01:35:10 INFO - 2015-11-25 01:35:10,313 INFO : Browser initialized. 01:35:10 INFO - 2015-11-25 01:35:10,314 INFO : Running cycle 1/1 for kraken test... 01:35:10 INFO - 2015-11-25 01:35:10,314 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoSWjf7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:35:22 INFO - 2015-11-25 01:35:22,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 138932224 01:35:22 INFO - 2015-11-25 01:35:22,221 DEBUG : BROWSER_OUTPUT: 01:35:24 INFO - 2015-11-25 01:35:24,828 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44891/tests/kraken/driver.html?testName=audio-beat-detection) 01:35:25 INFO - 2015-11-25 01:35:25,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 177238016 01:35:25 INFO - 2015-11-25 01:35:25,415 DEBUG : BROWSER_OUTPUT: 01:35:28 INFO - 2015-11-25 01:35:28,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44891/tests/kraken/driver.html?testName=audio-dft) 01:35:28 INFO - 2015-11-25 01:35:28,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 166858752 01:35:28 INFO - 2015-11-25 01:35:28,726 DEBUG : BROWSER_OUTPUT: 01:35:31 INFO - 2015-11-25 01:35:31,710 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44891/tests/kraken/driver.html?testName=audio-fft) 01:35:32 INFO - 2015-11-25 01:35:32,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 151822336 01:35:32 INFO - 2015-11-25 01:35:32,276 DEBUG : BROWSER_OUTPUT: 01:35:34 INFO - 2015-11-25 01:35:34,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44891/tests/kraken/driver.html?testName=audio-oscillator) 01:35:34 INFO - 2015-11-25 01:35:34,886 DEBUG : BROWSER_OUTPUT: RSS: Main: 152383488 01:35:34 INFO - 2015-11-25 01:35:34,886 DEBUG : BROWSER_OUTPUT: 01:35:37 INFO - 2015-11-25 01:35:37,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:35:37 INFO - 2015-11-25 01:35:37,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 153497600 01:35:37 INFO - 2015-11-25 01:35:37,698 DEBUG : BROWSER_OUTPUT: 01:35:40 INFO - 2015-11-25 01:35:40,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44891/tests/kraken/driver.html?testName=imaging-darkroom) 01:35:41 INFO - 2015-11-25 01:35:41,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 154968064 01:35:41 INFO - 2015-11-25 01:35:41,253 DEBUG : BROWSER_OUTPUT: 01:35:44 INFO - 2015-11-25 01:35:44,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44891/tests/kraken/driver.html?testName=imaging-desaturate) 01:35:45 INFO - 2015-11-25 01:35:45,192 DEBUG : BROWSER_OUTPUT: RSS: Main: 154894336 01:35:45 INFO - 2015-11-25 01:35:45,192 DEBUG : BROWSER_OUTPUT: 01:35:48 INFO - 2015-11-25 01:35:48,016 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44891/tests/kraken/driver.html?testName=json-parse-financial) 01:35:48 INFO - 2015-11-25 01:35:48,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 154603520 01:35:48 INFO - 2015-11-25 01:35:48,597 DEBUG : BROWSER_OUTPUT: 01:35:50 INFO - 2015-11-25 01:35:50,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:35:50 INFO - 2015-11-25 01:35:50,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 164745216 01:35:50 INFO - 2015-11-25 01:35:50,946 DEBUG : BROWSER_OUTPUT: 01:35:52 INFO - 2015-11-25 01:35:52,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:35:53 INFO - 2015-11-25 01:35:53,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 163532800 01:35:53 INFO - 2015-11-25 01:35:53,425 DEBUG : BROWSER_OUTPUT: 01:35:55 INFO - 2015-11-25 01:35:55,545 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:35:56 INFO - 2015-11-25 01:35:56,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 173604864 01:35:56 INFO - 2015-11-25 01:35:56,126 DEBUG : BROWSER_OUTPUT: 01:35:58 INFO - 2015-11-25 01:35:58,661 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:35:59 INFO - 2015-11-25 01:35:59,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 181166080 01:35:59 INFO - 2015-11-25 01:35:59,246 DEBUG : BROWSER_OUTPUT: 01:36:01 INFO - 2015-11-25 01:36:01,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:36:02 INFO - 2015-11-25 01:36:02,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 178606080 01:36:02 INFO - 2015-11-25 01:36:02,413 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,142 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44891/tests/kraken/driver.html?testName=ai-astar) 01:36:04 INFO - 2015-11-25 01:36:04,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 236912640 01:36:04 INFO - 2015-11-25 01:36:04,394 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;114;105;111;113;112;102;114;112;103 01:36:04 INFO - 2015-11-25 01:36:04,395 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;138;154;172;135;159;155;130;167;131 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;160;161;160;163;163;162;161 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;83;93;72;71;74;120;87;72 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;130;94;95;94;134;94;95;133 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;110;110;110;110;110;109;110;110 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;148;147;148;146;147;147;147;146 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;96;96;95;95;96;95 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;74;73;72;74;74;73;73;81;70;71 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;69;69;69;72;70;68;69 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;83;90;83;83;83;84;83;84;83 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;125;130;119;127;122;123;127;120;118 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;148;145;145;146;134;152;145;145;133 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;68;67;67;69;67;67;67;68;67 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:36:04 INFO - 2015-11-25 01:36:04,396 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,971 01:36:04 INFO - 2015-11-25 01:36:04,397 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:36:04 INFO - 2015-11-25 01:36:04,397 DEBUG : BROWSER_OUTPUT: __startTimestamp1448444164394__endTimestamp 01:36:04 INFO - 2015-11-25 01:36:04,397 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,404 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: Number of tests: 14 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.80 Median:112.00 stddev:4.61 (4.1%) stddev-sans-first:4.82 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: Values: 112.0 114.0 105.0 111.0 113.0 112.0 102.0 114.0 112.0 103.0 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.90 Median:156.50 stddev:15.23 (9.7%) stddev-sans-first:15.87 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: Values: 158.0 138.0 154.0 172.0 135.0 159.0 155.0 130.0 167.0 131.0 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.20 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 160.0 161.0 160.0 163.0 163.0 162.0 161.0 01:36:04 INFO - 2015-11-25 01:36:04,405 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.70 Median:73.50 stddev:15.48 (21.1%) stddev-sans-first:16.09 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 83.0 93.0 72.0 71.0 74.0 120.0 87.0 72.0 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:94.00 stddev:18.34 (19.5%) stddev-sans-first:19.03 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 130.0 94.0 95.0 94.0 134.0 94.0 95.0 133.0 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.80 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 110.0 01:36:04 INFO - 2015-11-25 01:36:04,406 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 148.0 147.0 148.0 146.0 147.0 147.0 147.0 146.0 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,407 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.50 Median:73.50 stddev:2.95 (4.0%) stddev-sans-first:3.13 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: Values: 74.0 73.0 72.0 74.0 74.0 73.0 73.0 81.0 70.0 71.0 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:1.20 (1.7%) stddev-sans-first:1.20 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 69.0 69.0 69.0 72.0 70.0 68.0 69.0 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:83.50 stddev:2.16 (2.6%) stddev-sans-first:2.29 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: Values: 84.0 83.0 90.0 83.0 83.0 83.0 84.0 83.0 84.0 83.0 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:125.00 stddev:3.89 (3.1%) stddev-sans-first:4.10 01:36:04 INFO - 2015-11-25 01:36:04,408 DEBUG : BROWSER_OUTPUT: Values: 125.0 125.0 130.0 119.0 127.0 122.0 123.0 127.0 120.0 118.0 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.00 Median:145.50 stddev:5.94 (4.1%) stddev-sans-first:6.20 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: Values: 147.0 148.0 145.0 145.0 146.0 134.0 152.0 145.0 145.0 133.0 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.73 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: Values: 69.0 68.0 67.0 67.0 69.0 67.0 67.0 67.0 68.0 67.0 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:36:04 INFO - 2015-11-25 01:36:04,409 DEBUG : BROWSER_OUTPUT: 01:36:05 INFO - 2015-11-25 01:36:05,277 INFO : Browser exited with error code: 0 01:36:05 INFO - 2015-11-25 01:36:05,330 INFO : Completed test kraken (00:01:00) 01:36:05 INFO - 2015-11-25 01:36:05,330 INFO : Starting test v8_7 01:36:05 INFO - 2015-11-25 01:36:05,331 DEBUG : operating with platform_type : linux_ 01:36:05 INFO - 2015-11-25 01:36:05,331 INFO : Initialising browser for v8_7 test... 01:36:05 INFO - 2015-11-25 01:36:05,337 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RYePA/profile http://localhost:44891/getInfo.html 01:36:09 INFO - 2015-11-25 01:36:09,317 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:36:09 INFO - 2015-11-25 01:36:09,317 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:36:09 INFO - 2015-11-25 01:36:09,318 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:36:09 INFO - 2015-11-25 01:36:09,318 DEBUG : BROWSER_OUTPUT: __metrics 01:36:09 INFO - 2015-11-25 01:36:09,495 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] 01:36:10 INFO - 2015-11-25 01:36:10,158 DEBUG : BROWSER_OUTPUT: console.error: 01:36:10 INFO - 2015-11-25 01:36:10,158 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 01:36:10 INFO - 2015-11-25 01:36:10,158 DEBUG : BROWSER_OUTPUT: Stack: 01:36:10 INFO - 2015-11-25 01:36:10,158 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 01:36:10 INFO - 2015-11-25 01:36:10,159 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 01:36:10 INFO - 2015-11-25 01:36:10,159 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 01:36:10 INFO - 2015-11-25 01:36:10,159 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 01:36:10 INFO - 2015-11-25 01:36:10,159 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 01:36:10 INFO - 2015-11-25 01:36:10,160 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 01:36:10 INFO - 2015-11-25 01:36:10,160 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 01:36:10 INFO - 2015-11-25 01:36:10,160 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 01:36:10 INFO - 2015-11-25 01:36:10,160 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 01:36:10 INFO - 2015-11-25 01:36:10,161 DEBUG : BROWSER_OUTPUT: 01:36:10 INFO - 2015-11-25 01:36:10,756 INFO : Browser initialized. 01:36:10 INFO - 2015-11-25 01:36:10,756 INFO : Running cycle 1/1 for v8_7 test... 01:36:10 INFO - 2015-11-25 01:36:10,757 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RYePA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 01:36:22 INFO - 2015-11-25 01:36:22,608 DEBUG : BROWSER_OUTPUT: RSS: Main: 138698752 01:36:22 INFO - 2015-11-25 01:36:22,608 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:44891/tests/v8_7/run.html (next: http://localhost:44891/tests/v8_7/run.html) 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 438931456 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: |0;Richards;164.39256945586058 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.6108422071636 01:36:44 INFO - 2015-11-25 01:36:44,486 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.00945232137894 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5128.205128205128 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1968.5039370078741 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |5;Earley;817.1428571428571 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |6;Boyer;12537.5 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |7;RegExp;26102.5641025641 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |8;Splay;593.8242280285035 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: __startTimestamp1448444204479__endTimestamp 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: Number of tests: 10 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.39 Median:164.39 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: Values: 164.4 01:36:44 INFO - 2015-11-25 01:36:44,487 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.61 Median:193.61 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: Values: 193.6 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.01 Median:278.01 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: Values: 278.0 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5128.21 Median:5128.21 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: Values: 5128.2 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1968.50 Median:1968.50 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: Values: 1968.5 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:817.14 Median:817.14 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: Values: 817.1 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,488 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12537.50 Median:12537.50 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: Values: 12537.5 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26102.56 Median:26102.56 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: Values: 26102.6 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.82 Median:593.82 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: Values: 593.8 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: Values: 4835.7 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:36:44 INFO - 2015-11-25 01:36:44,489 DEBUG : BROWSER_OUTPUT: 01:36:45 INFO - 2015-11-25 01:36:45,329 INFO : Browser exited with error code: 0 01:36:45 INFO - 2015-11-25 01:36:45,341 INFO : Completed test v8_7 (00:00:40) 01:36:45 INFO - 2015-11-25 01:36:45,775 INFO : Completed test suite (00:20:17) 01:36:45 INFO - 2015-11-25 01:36:45,775 DEBUG : Working with test: dromaeo_css 01:36:45 INFO - 2015-11-25 01:36:45,775 DEBUG : Generating results file: dromaeo_css 01:36:45 INFO - 2015-11-25 01:36:45,776 DEBUG : Working with test: kraken 01:36:45 INFO - 2015-11-25 01:36:45,777 DEBUG : Generating results file: kraken 01:36:45 INFO - 2015-11-25 01:36:45,777 INFO : javascript benchmark 01:36:45 INFO - 2015-11-25 01:36:45,777 DEBUG : Working with test: v8_7 01:36:45 INFO - 2015-11-25 01:36:45,777 DEBUG : Generating results file: v8_7 01:36:45 INFO - 2015-11-25 01:36:45,777 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:45 INFO - 2015-11-25 01:36:45,831 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,35]] 01:36:45 INFO - 2015-11-25 01:36:45,831 DEBUG : process_Request line: dromaeo_css 6343.07 graph.html#tests=[[72,132,35]] 01:36:45 INFO - 2015-11-25 01:36:45,831 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:45 INFO - 2015-11-25 01:36:45,865 DEBUG : process_Request line: kraken 1522.30 graph.html#tests=[[232,132,35]] 01:36:45 INFO - 2015-11-25 01:36:45,866 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:36:45 INFO - 2015-11-25 01:36:45,896 DEBUG : process_Request line: v8_7 19759.40 graph.html#tests=[[230,132,35]] 01:36:45 INFO - 2015-11-25 01:36:45,901 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2577.875399361022, 2703.428520275714, 2714.457108578285, 2715.253305341032, 2727.972720272797, 4474.843380481684, 4607.101615185032, 4621.58405743483, 4637.258038713802, 4651.953480465194, 4484.529124441936, 4554.043650833329, 4558.1567410029165, 4595.88510287243, 4640.651951103664, 4296.205202037633, 4350.825966961318, 4376.759278239696, 4378.912421751563, 4393.209222339978, 4451.821927122911, 4482.686211965148, 4505.36924830524, 4511.345854851044, 4525.389072475223, 4218.303979843322, 4247.68142389322, 4298.591633794785, 4308.073764140725, 4340.65274778017, 4345.109252603209, 4345.13097380522, 4348.130373925196, 4353.216421044211, 4390.736555806662, 4325.5025672047805, 4330.675199360061, 4343.478782546114, 4353.5428779977965, 4389.2977123660385, 4406.317020861771, 4526.162659907927, 4535.070310586322, 4544.795484203219, 4618.65360097994, 4971.279164521125, 5020.297158397827, 5023.32185155003, 5026.145555255615, 5070.2141168119, 5044.747762611854, 5048.747562621891, 5052.343195384613, 5053.216751403538, 5057.241413787961, 4519.276915693474, 4603.49361570227, 4633.212353899845, 4636.559526844944, 4702.0, 5095.592352611782, 5120.359955005624, 5121.9743901281, 5123.205903085028, 5131.050755610224, 4906.705597664151, 4924.704517728947, 4925.088858561177, 4926.33494478248, 4964.726940018261, 4809.975950120227, 4911.705297682151, 4914.287428322873, 4929.679570827885, 4990.401151861727, 4947.5794557462295, 4951.034548263054, 4954.1825598775795, 4956.677815942025, 4967.0, 4919.926201106986, 4945.035718034948, 4949.232868905326, 4950.653454258239, 4984.975075124601, 4030.7380020298588, 4068.6541643960595, 4108.568600296986, 4162.521310049323, 4199.895002624959, 3576.3383774001372, 3629.1834337273904, 3644.1800594865945, 3645.8359373828243, 3670.871519496805, 4303.892402689958, 4311.9784401077795, 4317.481902171697, 4317.589828966243, 4355.716878402894, 4815.3258543803895, 4915.926261106087, 5036.6726162799305, 5081.28861959326, 5083.923741143886, 4235.53409124996, 4240.300350442142, 4274.935875961863, 4294.098239369705, 4335.6965012449455, 4413.558644135561, 4417.116576684676, 4438.822447102144, 4457.04173602677, 4495.685302028826, 4497.145542346943, 4625.699329543569, 4704.858854234379, 4705.529447055335, 4714.127886340969, 4864.100141473838, 4931.7040977541465, 4957.305977163128, 4970.155073637563, 4982.701037937735, 4753.310769938271, 4773.451053128796, 4789.185838407409, 4796.808127674994, 4831.323614694016, 4958.057968985881, 4991.301217829434, 4991.875203119951, 4993.425756038102, 5044.848654540369, 4722.36248106515, 4821.686590371614, 4827.034593081327, 4867.70793752376, 4941.234108713155, 4882.755862206946, 4899.167141585867, 4902.8774280643265, 4916.336294600326, 4962.206047032458, 4867.70793752376, 4907.705537667752, 4914.631402644887, 4922.483139270325, 4963.205887058053, 4537.092581483783, 4634.513376095461, 4634.976825115853, 4638.0, 4748.62011039123, 4882.340883980617, 4935.679180853233, 4939.827106051271, 4941.184704523714, 4992.201247800334, 4823.758812059453, 4832.226843704991, 4837.274408838562, 4838.516148385274, 4882.658213924991], "unit": "ms", "name": "dojo.html", "value": 4577.1739551330475}, {"lowerIsBetter": false, "replicates": [546.2625455634893, 554.7337278106509, 558.8323502949114, 569.8803251317223, 573.5125143627916, 28077.157685269423, 28675.713242867565, 28704.13887583373, 35906.461403078974, 36804.0, 39220.411693824615, 39741.205175896466, 39812.40281395774, 39849.60150398495, 39866.80066599663, 7827.647755850972, 7990.280874721274, 7991.840163196733, 7995.0, 7998.840023199533, 7108.85782284354, 7321.084864391951, 10160.390376577392, 10416.166706663449, 10454.059134677878, 10122.443265620388, 10222.233332500093, 10239.641612543563, 10240.948795256014, 10245.743856403575, 7120.1099862517185, 7325.267473252632, 10026.69919902404, 10031.147352475049, 10139.138173255282, 10136.493175341204, 10369.066783989478, 10370.792584148276, 10370.896291037068, 10374.896251037468, 37127.62872371296, 37629.811850940845, 37657.62342376568, 37704.24591508154, 37778.24443511142, 2837.4041451295047, 2901.970980290212, 2929.0, 3175.301433684586, 3448.758586898918, 28353.73259370529, 28839.855800720863, 28935.0, 28951.710482895112, 29394.412111757858, 3371.578552680915, 3417.709494692954, 3428.468587368962, 3503.509508668763, 3548.503209550665, 230478.0, 230791.0, 231266.0, 232575.0, 232650.0, 49908.50091499111, 51396.22905656418, 54802.45197548053, 55247.17129243065, 55329.1700624491, 56909.715451422475, 58435.95474158469, 58485.0, 58539.0, 58539.70730146322, 12887.16233444823, 13103.803442948363, 13106.934465327613, 13109.147905386119, 13109.344532773322, 9781.608735650636, 9816.165625921734, 9829.55766990487, 9829.901700982899, 9840.868300145432, 17252.0, 17433.47699569015, 17872.463826085237, 18036.0, 18093.909530452525, 6742.49431292655, 6962.0, 7035.683394247271, 7109.28907109285, 7123.89314160288, 800.3237264511479, 815.9388045896582, 903.9186473217442, 971.2230215827451, 1001.534689972688, 26438.735612644014, 26531.86734066356, 26803.731962680515, 26883.462330753275, 26930.596041059398, 928.7863791328033, 955.8852937647388, 956.2015716876347, 964.8600952861796, 972.0910948263255, 13830.0, 13906.652333691738, 13919.791203131961, 14024.579262622137, 14105.224212668401, 262860.0, 264011.0, 266054.0, 266800.0, 267013.0, 36426.0, 45432.3185152223, 45873.311900321525, 45894.0, 45935.31097033547, 1056.3978532236706, 1104.7569534702513, 1168.065547561964, 1197.2995797458411, 1198.4367347346733, 23756.52486950286, 23952.76047239575, 24430.389240269134, 24444.51110977806, 24444.75555244496, 33504.16239594029, 33850.49224261589, 34912.825435872655, 35628.0, 40926.386104207864, 164427.0, 165279.1736041312, 166183.0, 166596.0, 166764.0, 26256.73743262543, 26744.331391715365, 26763.06329278466, 26964.05625803166, 26975.730242697322, 37085.62914370822, 37955.62044379521, 38398.80800595979, 39083.2183356337, 39531.0, 30050.982527346732, 31310.0, 31817.204569885937, 32313.15302704519, 32481.187970300933, 31464.685353146175, 31659.366812664077, 31869.840650797527, 31898.681013189573, 32322.0, 32095.0, 32698.34603307873, 32858.671413286516, 32900.1774955628, 32949.670503295616, 28877.566836497048, 28950.42099158047, 29445.411091778475, 29486.262843429085, 29534.409311814074, 22914.770852291265, 23379.88310058439, 23387.0, 23425.297241082793, 23535.646965295193], "unit": "ms", "name": "ext.html", "value": 16629.52148758554}, {"lowerIsBetter": false, "replicates": [339.78763272954404, 343.1164750766775, 344.4373858492814, 345.751059237349, 345.8288147367054, 12071.75856482872, 12534.81197782034, 15025.248737563119, 15130.773038404419, 15931.442399516034, 12148.651009558975, 13856.515021974234, 14778.187199704013, 14964.0, 15007.249637518134, 4956.504349565051, 5093.465186155456, 5100.77046532905, 5100.872478188038, 5101.15830887903, 3626.057225121461, 3743.3449146399407, 5058.848234552969, 5100.081985242673, 5102.795888164469, 3705.6479634434822, 3718.3307004739136, 3721.0883333583174, 3722.1066943933397, 5479.726013699319, 5627.8593035174035, 5750.712464376807, 5769.509591684712, 5829.213056237396, 5831.854203644922, 4994.35073440454, 4996.200607902754, 4998.725070121178, 5002.049610574016, 5019.924701129486, 19212.0, 20152.29466968664, 20387.796122038737, 20419.387418377322, 21077.051532680915, 1757.1038770227149, 1794.676958147533, 1846.021608547472, 1924.951876203092, 1986.9105890234973, 12314.876851231462, 12672.61982140528, 12931.383577052868, 13256.270905100215, 13962.301884905815, 1804.4496428589275, 1867.598466329732, 1877.6714075036814, 1885.2930151193302, 1907.1989764299028, 60460.395396046355, 61010.69494652543, 62598.37401626017, 66420.0, 67262.0, 15396.84603153954, 15548.301896195197, 15625.921870390803, 15656.13891235993, 15950.920245398698, 22866.42833929165, 22976.0, 23250.53498930012, 23257.88371058134, 23263.651045234335, 8330.125336839666, 8335.333173346118, 8377.078521362646, 8418.915810841936, 8438.831223375499, 7010.684519196649, 7066.50534462593, 7134.892976605355, 7147.964260178665, 7156.534825236343, 7569.394448444222, 7599.696012159562, 7624.961875190588, 7672.501287416411, 7826.8434631307055, 1747.6941535231283, 1779.2082523277277, 2199.82401407887, 2833.192540126054, 2941.3087924337765, 784.8077221080872, 797.2625321577517, 821.8767184922309, 838.7190291252499, 970.9174720148867, 15086.0, 15517.5344739658, 15808.051516909023, 15872.0, 16089.678206435805, 842.5997651115671, 870.6822009966317, 894.6376717429451, 931.1200915135132, 941.755143662639, 7485.4385921055045, 8560.443571167854, 8885.200331970154, 9164.587593558306, 9318.0, 475187.7443676695, 482513.0, 482614.0, 485562.0, 489295.0, 69406.65296673484, 70266.0, 70391.64804176049, 71227.64386178037, 72123.0, 997.0926456924019, 1003.1423133221136, 1086.5165001574383, 1107.0534692837668, 1107.1530279336152, 9661.855072174063, 9720.416774993522, 14563.708725825636, 14590.781138283146, 14640.853591464374, 31539.52690709595, 32380.35239295248, 32732.836335818167, 33037.66962330442, 35084.947451576496, 45731.77134114308, 46897.53102469068, 48376.30682926081, 48790.75604621954, 49319.260211096145, 13938.372773225432, 13949.362925608932, 14021.439142434188, 14063.507777227747, 14240.928795355956, 13150.671233219246, 17473.82526174722, 17504.649907002044, 17971.550711232325, 18174.273029079217, 11896.405179741152, 13761.518346857813, 14406.711865762416, 14417.855821441652, 14420.711585768435, 13681.726365472834, 13704.177749335071, 14220.715585688435, 14300.0, 14477.565673029825, 13962.441502339792, 14064.367103480572, 14146.151230926178, 14185.432582696989, 14376.496822611158, 12677.17598356104, 13005.0, 13266.33668316561, 13395.0, 13401.92784577251, 13906.930465347608, 14900.776488352467, 14922.552323430315, 15248.0, 15278.694426111193], "unit": "ms", "name": "jquery.html", "value": 9136.428676422964}, {"lowerIsBetter": false, "replicates": [1375.0993099519817, 1573.4807513520539, 1595.944141955032, 1603.1903888536287, 1642.039406946936, 5546.500814926656, 6605.27341992381, 6824.82937926552, 6851.109355783754, 6869.038334633149, 6772.424343930759, 6895.862082758342, 6923.792286231421, 6928.237893831687, 6930.965345173267, 1735.8264173582668, 1760.8943463392172, 2018.3944816555047, 2078.9480262993393, 2123.6389813731703, 2059.1969132038516, 2060.9587808243828, 2064.9793502064936, 2065.6488396972477, 2066.380085974209, 2008.116428771336, 2009.6784514477613, 2066.8036536529007, 2070.8343332533436, 2071.078370125295, 2021.3935819254166, 2067.503799088223, 2068.0, 2105.221068204766, 2105.589410065038, 2105.231590469483, 2125.4686328417897, 2131.8507704460694, 2133.1040962795664, 2134.210342173406, 6678.232003319632, 6755.864882702367, 6757.628330441824, 6779.32206779323, 6784.118064651613, 4118.135191609766, 4182.853600124012, 4193.853215137486, 4199.265128602482, 4226.682998775104, 2257.616205245096, 2692.6634170728657, 2798.720127987205, 2812.8874845006176, 2828.533292006837, 794.6702118620766, 829.0465964141285, 899.2401420799414, 939.286142531674, 948.7675519497698, 9203.401778884418, 9211.953940230323, 9238.676646317414, 9245.815083698288, 9255.352125351228, 3863.150106976461, 3932.115274063313, 3933.7049721270446, 3952.6047395260243, 3978.7214894957074, 7890.171531989174, 7898.644560994769, 7900.920990790019, 7907.0, 7918.168592297841, 4171.478565179353, 4188.6230239278, 4200.28595138821, 4202.726822756511, 4204.495460544754, 3805.5813860475328, 3839.3665045267217, 3840.8271627776817, 3843.0200298924156, 3845.9230815383535, 2744.3276397282793, 2768.6123942648046, 3092.6443459002053, 3121.4392803598203, 3340.8329583520726, 1065.0840277361463, 1079.6275285026654, 1083.6586475260267, 1090.9509072091776, 1092.3773449134076, 813.5403497024163, 817.1379194949267, 817.3052905030795, 870.1124852650262, 914.6478605736839, 6382.170317858741, 6441.130447389636, 6459.483241340681, 6480.189976252968, 6486.44865186455, 1004.3070281505884, 1008.4554340655964, 1027.0653705128298, 1030.701096681969, 1049.968500944973, 5023.34696489454, 5050.191969284897, 5059.418166910852, 5069.7465126744255, 5084.0, 8052.758417247492, 8119.269265765991, 8185.71350002747, 8191.754247372588, 8330.083690794008, 8110.391720620977, 8158.4289099762445, 8165.101838797727, 8178.713745018896, 8217.95891020553, 6290.779822706183, 6335.049742538564, 6375.649339286291, 6385.744570217233, 6401.295857455676, 5885.61743486672, 6105.5420843437805, 6117.480014198665, 6137.0, 6138.324784273637, 7055.259197784364, 7113.822154445973, 7174.892376614459, 7307.707691692485, 7500.174980752004, 7425.480216385017, 7544.509606875755, 7594.582297973665, 7662.310392064741, 7681.462297639335, 6046.848828779316, 6117.143599896107, 6127.019676851861, 6134.570580059353, 6136.815895523141, 5453.972730136483, 5686.800961966312, 5698.886022279448, 6005.429484198994, 6342.936570634234, 6060.151578778887, 6062.575619706755, 6088.969555152196, 6136.2636483622255, 6199.318075011655, 5875.471207591167, 5954.0, 5971.373005834499, 5995.340512543704, 6064.545159113172, 6119.541034422347, 6121.84695382619, 6129.754809807558, 6199.752009919733, 6203.472704820141, 5674.460926212003, 5702.258706368146, 5703.20155178267, 5703.372629010722, 5753.827385178452, 5644.9435505644415, 5655.208270842168, 5660.037793575019, 5673.290838645169, 5681.687507186979], "unit": "ms", "name": "mootools.html", "value": 3936.2115741093694}, {"lowerIsBetter": false, "replicates": [309.89153796171337, 314.41361860130866, 317.1120861587554, 319.7074676670847, 320.13244108466057, 5066.341375621173, 5105.719185444804, 5249.186376111699, 5314.202869569566, 5329.360476742787, 5044.747762611873, 5148.948510514893, 5167.069927413064, 5202.3497062867145, 5203.531682148606, 3897.2400381925536, 4012.2577323195155, 4156.875293741177, 4160.500739911215, 4169.937450938239, 4076.6331030207275, 4178.958210417887, 4188.97905510472, 4196.076863090116, 4221.873343799691, 4120.567340429257, 4127.752334859903, 4135.648469880049, 4138.4413104231, 4139.296319625671, 4121.361189015707, 4150.667946564267, 4154.709170358076, 4164.083901541671, 4185.58144185582, 3692.5753538343165, 3705.7776533408082, 3708.592054873962, 3708.9258214835554, 3709.239605880788, 4885.5358740919555, 5024.196128619404, 5031.06925218832, 5069.569086627641, 5085.949140508622, 4795.736234507101, 4808.591269742076, 4810.628970743356, 4811.4707382187935, 4811.542903424169, 5307.230451584537, 5448.727563621803, 5612.186232996192, 5622.887542249173, 5633.464820842013, 3901.9219615607813, 3995.420664003703, 4004.599540046001, 4027.939580906289, 4029.738067025634, 5748.856278593069, 5794.536437085023, 5841.357450680422, 5842.795502157404, 5843.415658434165, 4118.855840045584, 4140.585941405896, 4151.3772934059525, 4155.293600087993, 4156.04410985469, 4080.2451546464, 4197.937030944478, 4204.894877628084, 4208.768517731554, 4247.17180149874, 4734.573888350065, 4760.476347601761, 4772.0694464579765, 4773.618110551217, 4785.545373189541, 5579.302587176603, 5597.74810133545, 5606.6916319602, 5608.074667679787, 5616.803411880564, 1474.9262536873114, 1498.4980031689506, 1499.6850661361018, 1500.2123884960483, 1500.48983345663, 1034.7930413917247, 1036.4092467293722, 1037.2108113504296, 1038.8597539332245, 1060.4644654449453, 4818.036392721469, 4821.325014497973, 4822.276658501182, 4822.493638168012, 4825.372701548777, 5700.9429905701245, 5703.800366987136, 5705.0, 5712.143178523254, 5714.714264286769, 4817.614590832795, 4827.034593081327, 4828.155072862235, 4831.613470922318, 4832.637552183601, 3010.1722026442903, 3081.659478127022, 3093.211231136046, 3115.501519756873, 3129.2802655389387, 5492.642978206483, 5507.779688812483, 5514.6139770216505, 5514.751836167382, 5516.668999860021, 5976.581639285295, 5982.611130276605, 6002.459778619946, 6012.338642749294, 6019.277686677627, 5480.561555075505, 5490.203920431515, 5490.890182196334, 5491.121420572769, 5504.4770746778995, 5575.9163612545035, 5672.971635141798, 5736.71316434185, 5833.270841144857, 5843.883122337445, 5598.86002849932, 5693.259876216065, 5706.343770466284, 5714.3714191438075, 5722.6852523109965, 5481.232627432083, 5482.725863706719, 5487.451254874481, 5489.066858634122, 5492.890142197213, 3977.9005524860954, 4015.0765323975065, 4023.8390464381096, 4026.476558047435, 4031.254217969685, 5644.8024319148635, 5808.983427900185, 5819.214406055297, 5822.097574875901, 5832.679202643895, 5609.27079479665, 5614.9719251403485, 5616.747246373841, 5618.747156377891, 5622.606417550732, 5603.831885043455, 5612.242347283064, 5614.550835933034, 5615.241942337732, 5616.69108199053, 5313.574914006947, 5490.890182196254, 5491.917621235764, 5499.312585926759, 5515.034868898007, 5613.859653508695, 5651.943480565142, 5656.2929633795775, 5662.745176467151, 5666.263385759825, 5514.91727624078, 5532.529734972572, 5533.0, 5535.916961245504, 5539.944600553943], "unit": "ms", "name": "prototype.html", "value": 4226.367727310405}, {"lowerIsBetter": false, "replicates": [626.4424662050773, 690.7168061094949, 701.2181417719243, 756.7389250708503, 772.5210369570865, 11553.711157221074, 11670.41647917605, 11692.415379231037, 11710.121740869427, 11717.0626349892, 11511.079113670907, 11637.709057273572, 11703.239289446181, 11757.176997610171, 11829.7634047319, 613.1171113596429, 621.5276389943652, 766.7699690092978, 783.8706613408781, 785.7524879662916, 773.5126870071847, 783.2832957843566, 789.6328207383566, 789.8933643958078, 790.7034861926777, 814.7352110564091, 822.7079386817684, 823.1685997142899, 824.2128767027474, 824.505296821908, 629.6725702634627, 638.4764493115647, 801.6793282686914, 809.8947136872227, 818.758466252457, 605.7032054293364, 605.7365046204915, 605.8000859716283, 605.9242594675666, 606.2664176346626, 11264.21150519464, 11365.374904380256, 11424.257423267461, 11463.312201267867, 11533.250338727956, 11536.596219132374, 11637.941810290895, 11850.288982660983, 12055.517779288817, 12169.330686812222, 9270.165685088306, 9404.167598042828, 9523.380980236263, 9540.379875308083, 9551.235901127893, 3330.7834990725523, 3355.228297491566, 3383.881564145268, 3391.084407210064, 3391.135260508579, 42107.57892421067, 42405.57594424078, 42509.36235956463, 42994.0, 43087.784561076995, 8939.240164586083, 9035.412698174729, 9171.266298696088, 9180.265578753684, 9250.4912229828, 16257.918710406373, 16410.917945410434, 16419.1790410479, 16437.753433698505, 16447.588810279838, 6211.0, 6334.239891213084, 6363.33185015576, 6366.681665916778, 6443.194600674916, 3523.1544429337287, 3578.7137029037613, 3594.4967704521387, 3686.9262614747554, 3689.7970611616083, 2751.7385848344375, 2789.4002789400374, 2807.6350074490188, 2820.2244382794483, 2823.280063583814, 499.29599265036126, 500.1213165569915, 505.19673718787305, 506.44291279592153, 507.93650793650795, 408.9427480152781, 426.1753506963973, 457.4898987628836, 514.8043743377492, 538.0798833993836, 7389.261073892568, 7491.625418729151, 7498.400127989857, 7608.125065617414, 7640.80897977555, 476.5353780064479, 512.3467578837012, 546.9015577196063, 549.0748089469178, 564.9604527683023, 3600.135967367813, 3613.3282404122842, 3689.1699367642605, 3701.9629803702155, 3725.6088110748524, 49695.25457118146, 50634.0, 50663.7466812671, 50668.49331506638, 50806.23790643143, 43547.78226108849, 43593.56406435895, 43789.78105109454, 44251.33622995658, 44351.11297774027, 41758.791206043774, 41988.37017444741, 42398.15203695847, 42473.36289955653, 42513.57486425158, 7726.7681969541, 7805.492642978188, 7955.473906778251, 7984.960075199587, 8137.186281371815, 31260.0, 31279.53080703837, 31851.68148318487, 31975.520367194047, 32197.67802321947, 37609.623903760614, 42911.78544107259, 43231.0, 43613.56386436095, 44527.0, 9469.952650236704, 9663.371880828005, 9911.65309214174, 9921.156701680438, 9962.900370996198, 10357.689269321932, 10493.108085812792, 10535.473226338805, 10536.578536858746, 10879.238453308193, 9956.601735930772, 10012.749681258027, 10076.244281678757, 10157.136643385395, 10286.434246116536, 10099.94950025245, 10167.084962353423, 10191.694249172537, 10246.948765256126, 10357.274990750575, 9883.258755593502, 9898.0, 10127.139193168663, 10176.389416635026, 10402.531886064993, 8797.648094076165, 9134.314926380415, 9161.86257206129, 9251.121143491358, 9363.578638961399, 8253.917460825314, 8371.455855369382, 8524.701635442978, 8711.738647840575, 9159.86260206084], "unit": "ms", "name": "yui.html", "value": 5629.259010202965}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 114.0, 105.0, 111.0, 113.0, 112.0, 102.0, 114.0, 112.0, 103.0], "unit": "ms", "name": "ai-astar", "value": 109.8}, {"lowerIsBetter": true, "replicates": [158.0, 138.0, 154.0, 172.0, 135.0, 159.0, 155.0, 130.0, 167.0, 131.0], "unit": "ms", "name": "audio-beat-detection", "value": 149.9}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 160.0, 163.0, 163.0, 162.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 83.0, 93.0, 72.0, 71.0, 74.0, 120.0, 87.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.7}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 130.0, 94.0, 95.0, 94.0, 134.0, 94.0, 95.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.8}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 148.0, 147.0, 148.0, 146.0, 147.0, 147.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 72.0, 74.0, 74.0, 73.0, 73.0, 81.0, 70.0, 71.0], "unit": "ms", "name": "json-parse-financial", "value": 73.5}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 69.0, 69.0, 72.0, 70.0, 68.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.1}, {"lowerIsBetter": true, "replicates": [84.0, 83.0, 90.0, 83.0, 83.0, 83.0, 84.0, 83.0, 84.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.0}, {"lowerIsBetter": true, "replicates": [125.0, 125.0, 130.0, 119.0, 127.0, 122.0, 123.0, 127.0, 120.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 123.6}, {"lowerIsBetter": true, "replicates": [147.0, 148.0, 145.0, 145.0, 146.0, 134.0, 152.0, 145.0, 145.0, 133.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 144.0}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 67.0, 67.0, 69.0, 67.0, 67.0, 67.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.39256945586058], "unit": "score", "name": "Richards", "value": 214.742066}, {"lowerIsBetter": false, "replicates": [193.6108422071636], "unit": "score", "name": "DeltaBlue", "value": 341.49946999999986}, {"lowerIsBetter": false, "replicates": [278.00945232137894], "unit": "score", "name": "Encrypt", "value": 957.4530570000002}, {"lowerIsBetter": false, "replicates": [5128.205128205128], "unit": "score", "name": "Decrypt", "value": 51.90529499999997}, {"lowerIsBetter": false, "replicates": [1968.5039370078741], "unit": "score", "name": "RayTrace", "value": 375.9144119999999}, {"lowerIsBetter": false, "replicates": [817.1428571428571], "unit": "score", "name": "Earley", "value": 815.6015734265739}, {"lowerIsBetter": false, "replicates": [12537.5], "unit": "score", "name": "Boyer", "value": 53.15756729810566}, {"lowerIsBetter": false, "replicates": [26102.5641025641], "unit": "score", "name": "RegExp", "value": 34.90021119842827}, {"lowerIsBetter": false, "replicates": [593.8242280285035], "unit": "score", "name": "Splay", "value": 137.23084400000005}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7"}]} 01:36:45 INFO - RETURN: dromaeo_css: 6343.07 01:36:45 INFO - RETURN: kraken: 1522.30 01:36:45 INFO - RETURN: v8_7: 19759.40 01:36:45 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,35]]", "result": "19759.40"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,35]]", "result": "1522.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,35]]", "result": "6343.07"}}} 01:36:45 INFO - Return code: 0 01:36:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:36:46 INFO - # TBPL SUCCESS # 01:36:46 INFO - Running post-action listener: _resource_record_post_action 01:36:46 INFO - Running post-run listener: _resource_record_post_run 01:36:47 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506933248; Read time: 4; Write time: 372076 01:36:47 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 222728192; Read time: 0; Write time: 236376 01:36:47 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 271597568; Read time: 0; Write time: 132548 01:36:47 INFO - Running post-run listener: _upload_blobber_files 01:36:47 INFO - Blob upload gear active. 01:36:47 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:36:47 INFO - Copying logs to upload dir... 01:36:47 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1269.542944 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2015-11-25 01:36:46.957936) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 01:36:46.961395) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009958 build_url: 'https://queue.taskcluster.net/v1/task/2L9KRq_2QOan3YsW4JnRTw/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-25 01:36:47.012293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:36:47.012611) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1448442855.863138-272689563 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004620 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 01:36:47.062187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 01:36:47.062535) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 01:36:47.062822) ========= ========= Total master_lag: 0.27 =========