builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1450142542.01 results: success (0) buildid: 20151214160119 builduid: 79f640b1f6a5416ab2fd21fea91f82bb revision: ae37fdb042c07c0cb9d0afcd41372a96454f4f4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.015185) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.015750) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.016049) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.005123 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.037596) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.037899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.051223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.051634) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.099948) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.100255) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.100648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.100952) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-14 17:22:22-- 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% 219M=0s 2015-12-14 17:22:22 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108026 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.221327) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.221637) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.271399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.271789) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae37fdb042c07c0cb9d0afcd41372a96454f4f4f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ae37fdb042c07c0cb9d0afcd41372a96454f4f4f --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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-14 17:22:22,372 truncating revision to first 12 chars 2015-12-14 17:22:22,372 Setting DEBUG logging. 2015-12-14 17:22:22,372 attempt 1/10 2015-12-14 17:22:22,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae37fdb042c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-14 17:22:22,697 unpacking tar archive at: mozilla-central-ae37fdb042c0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.610208 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.894839) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.895155) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.902847) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.903234) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-14 17:22:22.903707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-12-14 17:22:22.904049) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:22:23 INFO - MultiFileLogger online at 20151214 17:22:23 in /builds/slave/test 17:22:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:22:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:22:23 INFO - {'append_to_log': False, 17:22:23 INFO - 'base_work_dir': '/builds/slave/test', 17:22:23 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:22:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:22:23 INFO - 'branch': 'Firefox-Non-PGO', 17:22:23 INFO - 'buildbot_json_path': 'buildprops.json', 17:22:23 INFO - 'config_files': ('talos/linux_config.py',), 17:22:23 INFO - 'default_actions': ('clobber', 17:22:23 INFO - 'read-buildbot-config', 17:22:23 INFO - 'download-and-extract', 17:22:23 INFO - 'populate-webroot', 17:22:23 INFO - 'create-virtualenv', 17:22:23 INFO - 'install', 17:22:23 INFO - 'run-tests'), 17:22:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:22:23 INFO - 'download_minidump_stackwalk': True, 17:22:23 INFO - 'download_symbols': 'ondemand', 17:22:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:22:23 INFO - 'tooltool.py': '/tools/tooltool.py', 17:22:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:22:23 INFO - '/tools/misc-python/virtualenv.py')}, 17:22:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:22:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:22:23 INFO - 'installer_path': 'installer.exe', 17:22:23 INFO - 'log_level': 'info', 17:22:23 INFO - 'log_name': 'talos', 17:22:23 INFO - 'log_to_console': True, 17:22:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:22:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:22:23 INFO - 'opt_config_files': (), 17:22:23 INFO - 'pip_index': False, 17:22:23 INFO - 'sps_profile': False, 17:22:23 INFO - 'sps_profile_interval': 0, 17:22:23 INFO - 'suite': 'dromaeojs', 17:22:23 INFO - 'system_bits': '64', 17:22:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:22:23 INFO - 'title': 'talos-linux64-ix-018', 17:22:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:22:23 INFO - 'use_talos_json': True, 17:22:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:22:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:22:23 INFO - 'work_dir': 'build'} 17:22:23 INFO - ##### 17:22:23 INFO - ##### Running clobber step. 17:22:23 INFO - ##### 17:22:23 INFO - Running pre-action listener: _resource_record_pre_action 17:22:23 INFO - Running main action method: clobber 17:22:23 INFO - rmtree: /builds/slave/test/build 17:22:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:22:23 INFO - Running post-action listener: _resource_record_post_action 17:22:23 INFO - ##### 17:22:23 INFO - ##### Running read-buildbot-config step. 17:22:23 INFO - ##### 17:22:23 INFO - Running pre-action listener: _resource_record_pre_action 17:22:23 INFO - Running main action method: read_buildbot_config 17:22:23 INFO - Using buildbot properties: 17:22:23 INFO - { 17:22:23 INFO - "properties": { 17:22:23 INFO - "buildnumber": 346, 17:22:23 INFO - "product": "firefox", 17:22:23 INFO - "script_repo_revision": "production", 17:22:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 17:22:23 INFO - "repository": "", 17:22:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 17:22:23 INFO - "buildid": "20151214160119", 17:22:23 INFO - "slavename": "talos-linux64-ix-018", 17:22:23 INFO - "pgo_build": "False", 17:22:23 INFO - "basedir": "/builds/slave/test", 17:22:23 INFO - "project": "", 17:22:23 INFO - "platform": "ubuntu64_hw", 17:22:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:22:23 INFO - "slavebuilddir": "test", 17:22:23 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 17:22:23 INFO - "branch": "mozilla-central", 17:22:23 INFO - "repo_path": "mozilla-central", 17:22:23 INFO - "stage_platform": "linux64", 17:22:23 INFO - "builduid": "79f640b1f6a5416ab2fd21fea91f82bb", 17:22:23 INFO - "revision": "ae37fdb042c07c0cb9d0afcd41372a96454f4f4f" 17:22:23 INFO - }, 17:22:23 INFO - "sourcestamp": { 17:22:23 INFO - "repository": "", 17:22:23 INFO - "hasPatch": false, 17:22:23 INFO - "project": "", 17:22:23 INFO - "branch": "mozilla-central-linux64-talos", 17:22:23 INFO - "changes": [ 17:22:23 INFO - { 17:22:23 INFO - "category": null, 17:22:23 INFO - "files": [ 17:22:23 INFO - { 17:22:23 INFO - "url": null, 17:22:23 INFO - "name": "https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2" 17:22:23 INFO - } 17:22:23 INFO - ], 17:22:23 INFO - "repository": "", 17:22:23 INFO - "rev": "ae37fdb042c07c0cb9d0afcd41372a96454f4f4f", 17:22:23 INFO - "who": "kwierso@gmail.com", 17:22:23 INFO - "when": 1450142240, 17:22:23 INFO - "number": 7360010, 17:22:23 INFO - "comments": "Bump some telemetry histogram expirations up to 47 to help unbreak m-c ASAP rs=mossop (bug 1232493, bug 1232494, and bug 1232496) a=bustage", 17:22:23 INFO - "project": "", 17:22:23 INFO - "at": "Mon 14 Dec 2015 17:17:20", 17:22:23 INFO - "branch": "mozilla-central-linux64-talos", 17:22:23 INFO - "revlink": "", 17:22:23 INFO - "properties": [ 17:22:23 INFO - [ 17:22:23 INFO - "buildid", 17:22:23 INFO - "20151214160119", 17:22:23 INFO - "Change" 17:22:23 INFO - ], 17:22:23 INFO - [ 17:22:23 INFO - "builduid", 17:22:23 INFO - "79f640b1f6a5416ab2fd21fea91f82bb", 17:22:23 INFO - "Change" 17:22:23 INFO - ], 17:22:23 INFO - [ 17:22:23 INFO - "pgo_build", 17:22:23 INFO - "False", 17:22:23 INFO - "Change" 17:22:23 INFO - ] 17:22:23 INFO - ], 17:22:23 INFO - "revision": "ae37fdb042c07c0cb9d0afcd41372a96454f4f4f" 17:22:23 INFO - } 17:22:23 INFO - ], 17:22:23 INFO - "revision": "ae37fdb042c07c0cb9d0afcd41372a96454f4f4f" 17:22:23 INFO - } 17:22:23 INFO - } 17:22:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 17:22:23 INFO - Running post-action listener: _resource_record_post_action 17:22:23 INFO - ##### 17:22:23 INFO - ##### Running download-and-extract step. 17:22:23 INFO - ##### 17:22:23 INFO - Running pre-action listener: _resource_record_pre_action 17:22:23 INFO - Running main action method: download_and_extract 17:22:23 INFO - mkdir: /builds/slave/test/build/tests 17:22:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:23 INFO - https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:22:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/test_packages.json 17:22:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/test_packages.json 17:22:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 17:22:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 17:22:24 INFO - Downloaded 1302 bytes. 17:22:24 INFO - Reading from file /builds/slave/test/build/test_packages.json 17:22:24 INFO - Using the following test package requirements: 17:22:24 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:22:24 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:22:24 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'jsshell-linux-x86_64.zip'], 17:22:24 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:22:24 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:22:24 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:22:24 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:22:24 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:22:24 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:22:24 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:22:24 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:22:24 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:24 INFO - https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:22:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:22:25 INFO - Downloaded 21869726 bytes. 17:22:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:22:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:25 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:22:26 INFO - Return code: 0 17:22:26 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:26 INFO - https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:22:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:22:27 INFO - Downloaded 21869726 bytes. 17:22:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 17:22:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:22:27 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:22:28 INFO - Return code: 0 17:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:28 INFO - https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:22:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:22:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:22:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:22:29 INFO - Downloaded 11132038 bytes. 17:22:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 17:22:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:22:29 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 17:22:29 INFO - Return code: 0 17:22:29 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')]} 17:22:29 INFO - https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:22:31 INFO - Downloaded 57739123 bytes. 17:22:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:22:31 INFO - mkdir: /builds/slave/test/properties 17:22:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:22:31 INFO - Writing to file /builds/slave/test/properties/build_url 17:22:31 INFO - Contents: 17:22:31 INFO - build_url:https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:22:31 INFO - Running post-action listener: _resource_record_post_action 17:22:31 INFO - Running post-action listener: set_extra_try_arguments 17:22:31 INFO - ##### 17:22:31 INFO - ##### Running populate-webroot step. 17:22:31 INFO - ##### 17:22:31 INFO - Running pre-action listener: _resource_record_pre_action 17:22:31 INFO - Running main action method: populate_webroot 17:22:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:22:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:22:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:22:31 INFO - '--tppagecycles', 17:22:31 INFO - '7'], 17:22:31 INFO - 'tests': ['tsvgm']}}, 17:22:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:22:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:22:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:22:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:22:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:22:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:22:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'tests': ['damp', 'tps']}, 17:22:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['damp', 'tps']}, 17:22:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:22:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:22:31 INFO - 'other': {'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'other_l64': {'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'other_nol64': {'tests': ['a11yr', 17:22:31 INFO - 'ts_paint', 17:22:31 INFO - 'tpaint', 17:22:31 INFO - 'sessionrestore', 17:22:31 INFO - 'sessionrestore_no_auto_restore']}, 17:22:31 INFO - 'svgr': {'tests': ['tsvgx', 17:22:31 INFO - 'tsvgr_opacity', 17:22:31 INFO - 'tart', 17:22:31 INFO - 'tscrollx', 17:22:31 INFO - 'cart']}, 17:22:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['tsvgx', 17:22:31 INFO - 'tsvgr_opacity', 17:22:31 INFO - 'tart', 17:22:31 INFO - 'tscrollx', 17:22:31 INFO - 'cart']}, 17:22:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'tests': ['tp5o']}, 17:22:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'talos_options': ['--e10s'], 17:22:31 INFO - 'tests': ['tp5o']}, 17:22:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'talos_options': ['--xperf_path', 17:22:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:22:31 INFO - 'tests': ['tp5n']}, 17:22:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:22:31 INFO - 'talos_options': ['--e10s', 17:22:31 INFO - '--xperf_path', 17:22:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:22:31 INFO - 'tests': ['tp5n']}}, 17:22:31 INFO - 'talos.zip': {'path': '', 17:22:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:22:31 INFO - Running post-action listener: _resource_record_post_action 17:22:31 INFO - ##### 17:22:31 INFO - ##### Running create-virtualenv step. 17:22:31 INFO - ##### 17:22:31 INFO - Running pre-action listener: _resource_record_pre_action 17:22:31 INFO - Running main action method: create_virtualenv 17:22:31 INFO - Creating virtualenv /builds/slave/test/build/venv 17:22:31 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 17:22:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:22:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:22:31 INFO - Using real prefix '/usr' 17:22:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:22:31 INFO - Installing distribute.............................................................................................................................................................................................done. 17:22:33 INFO - Installing pip.................done. 17:22:33 INFO - Return code: 0 17:22:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:33 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:33 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 17:22:33 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 17:22:33 INFO - Using env: {'DISPLAY': ':0', 17:22:33 INFO - 'HOME': '/home/cltbld', 17:22:33 INFO - 'LANG': 'en_US.UTF-8', 17:22:33 INFO - 'LANGUAGE': 'en_US:en', 17:22:33 INFO - 'LOGNAME': 'cltbld', 17:22:33 INFO - 'MAIL': '/var/mail/cltbld', 17:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:33 INFO - 'MOZ_NO_REMOTE': '1', 17:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:33 INFO - 'NO_EM_RESTART': '1', 17:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:33 INFO - 'PWD': '/builds/slave/test', 17:22:33 INFO - 'SHELL': '/bin/bash', 17:22:33 INFO - 'SHLVL': '1', 17:22:33 INFO - 'TERM': 'linux', 17:22:33 INFO - 'TMOUT': '86400', 17:22:33 INFO - 'USER': 'cltbld', 17:22:33 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:33 INFO - '_': '/tools/buildbot/bin/python'} 17:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:33 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 17:22:33 INFO - Cleaning up... 17:22:33 INFO - Return code: 0 17:22:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:33 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:33 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 17:22:33 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 17:22:33 INFO - Using env: {'DISPLAY': ':0', 17:22:33 INFO - 'HOME': '/home/cltbld', 17:22:33 INFO - 'LANG': 'en_US.UTF-8', 17:22:33 INFO - 'LANGUAGE': 'en_US:en', 17:22:33 INFO - 'LOGNAME': 'cltbld', 17:22:33 INFO - 'MAIL': '/var/mail/cltbld', 17:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:33 INFO - 'MOZ_NO_REMOTE': '1', 17:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:33 INFO - 'NO_EM_RESTART': '1', 17:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:33 INFO - 'PWD': '/builds/slave/test', 17:22:33 INFO - 'SHELL': '/bin/bash', 17:22:33 INFO - 'SHLVL': '1', 17:22:33 INFO - 'TERM': 'linux', 17:22:33 INFO - 'TMOUT': '86400', 17:22:33 INFO - 'USER': 'cltbld', 17:22:33 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:33 INFO - '_': '/tools/buildbot/bin/python'} 17:22:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:34 INFO - Downloading/unpacking psutil>=0.7.1 17:22:34 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 17:22:34 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 17:22:34 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 17:22:34 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 17:22:34 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 17:22:34 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 17:22:35 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 17:22:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:22:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:22:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:22:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:22:35 INFO - Installing collected packages: psutil 17:22:35 INFO - Running setup.py install for psutil 17:22:35 INFO - building 'psutil._psutil_linux' extension 17:22:35 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 17:22:36 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 17:22:36 INFO - building 'psutil._psutil_posix' extension 17:22:36 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 17:22:36 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 17:22:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:22:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:22:36 INFO - Successfully installed psutil 17:22:36 INFO - Cleaning up... 17:22:36 INFO - Return code: 0 17:22:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:36 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:36 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 17:22:36 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 17:22:36 INFO - Using env: {'DISPLAY': ':0', 17:22:36 INFO - 'HOME': '/home/cltbld', 17:22:36 INFO - 'LANG': 'en_US.UTF-8', 17:22:36 INFO - 'LANGUAGE': 'en_US:en', 17:22:36 INFO - 'LOGNAME': 'cltbld', 17:22:36 INFO - 'MAIL': '/var/mail/cltbld', 17:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:36 INFO - 'MOZ_NO_REMOTE': '1', 17:22:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:36 INFO - 'NO_EM_RESTART': '1', 17:22:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:36 INFO - 'PWD': '/builds/slave/test', 17:22:36 INFO - 'SHELL': '/bin/bash', 17:22:36 INFO - 'SHLVL': '1', 17:22:36 INFO - 'TERM': 'linux', 17:22:36 INFO - 'TMOUT': '86400', 17:22:36 INFO - 'USER': 'cltbld', 17:22:36 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:36 INFO - '_': '/tools/buildbot/bin/python'} 17:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:22:36 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 17:22:36 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 17:22:36 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 17:22:36 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 17:22:36 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 17:22:36 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 17:22:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:22:38 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:22:38 INFO - Installing collected packages: mozsystemmonitor 17:22:38 INFO - Running setup.py install for mozsystemmonitor 17:22:38 INFO - Successfully installed mozsystemmonitor 17:22:38 INFO - Cleaning up... 17:22:38 INFO - Return code: 0 17:22:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:22: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')]} 17:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22: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')]} 17:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:22:38 INFO - Using env: {'DISPLAY': ':0', 17:22:38 INFO - 'HOME': '/home/cltbld', 17:22:38 INFO - 'LANG': 'en_US.UTF-8', 17:22:38 INFO - 'LANGUAGE': 'en_US:en', 17:22:38 INFO - 'LOGNAME': 'cltbld', 17:22:38 INFO - 'MAIL': '/var/mail/cltbld', 17:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:38 INFO - 'MOZ_NO_REMOTE': '1', 17:22:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:38 INFO - 'NO_EM_RESTART': '1', 17:22:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:38 INFO - 'PWD': '/builds/slave/test', 17:22:38 INFO - 'SHELL': '/bin/bash', 17:22:38 INFO - 'SHLVL': '1', 17:22:38 INFO - 'TERM': 'linux', 17:22:38 INFO - 'TMOUT': '86400', 17:22:38 INFO - 'USER': 'cltbld', 17:22:38 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:38 INFO - '_': '/tools/buildbot/bin/python'} 17:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:38 INFO - Downloading/unpacking blobuploader==1.2.4 17:22:38 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 17:22:38 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 17:22:38 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 17:22:38 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 17:22:38 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 17:22:38 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 17:22:40 INFO - Downloading blobuploader-1.2.4.tar.gz 17:22:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:22:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:22:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:40 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 17:22:41 INFO - Downloading docopt-0.6.1.tar.gz 17:22:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:22:41 INFO - Installing collected packages: blobuploader, requests, docopt 17:22:41 INFO - Running setup.py install for blobuploader 17:22:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:22:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:22:41 INFO - Running setup.py install for requests 17:22:41 INFO - Running setup.py install for docopt 17:22:41 INFO - Successfully installed blobuploader requests docopt 17:22:41 INFO - Cleaning up... 17:22:41 INFO - Return code: 0 17:22:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:41 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:41 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 17:22:41 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 17:22:41 INFO - Using env: {'DISPLAY': ':0', 17:22:41 INFO - 'HOME': '/home/cltbld', 17:22:41 INFO - 'LANG': 'en_US.UTF-8', 17:22:41 INFO - 'LANGUAGE': 'en_US:en', 17:22:41 INFO - 'LOGNAME': 'cltbld', 17:22:41 INFO - 'MAIL': '/var/mail/cltbld', 17:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:41 INFO - 'MOZ_NO_REMOTE': '1', 17:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:41 INFO - 'NO_EM_RESTART': '1', 17:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:41 INFO - 'PWD': '/builds/slave/test', 17:22:41 INFO - 'SHELL': '/bin/bash', 17:22:41 INFO - 'SHLVL': '1', 17:22:41 INFO - 'TERM': 'linux', 17:22:41 INFO - 'TMOUT': '86400', 17:22:41 INFO - 'USER': 'cltbld', 17:22:41 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:41 INFO - '_': '/tools/buildbot/bin/python'} 17:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:22:41 INFO - Running setup.py (path:/tmp/pip-DNeNsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:22:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:22:41 INFO - Running setup.py (path:/tmp/pip-zlCghW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:22:42 INFO - Running setup.py (path:/tmp/pip-qb6Hwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:22:42 INFO - Running setup.py (path:/tmp/pip-6IsNOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:22:42 INFO - Running setup.py (path:/tmp/pip-97N52C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:22:42 INFO - Running setup.py (path:/tmp/pip-p4IpJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:22:42 INFO - Running setup.py (path:/tmp/pip-pHFJw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:22:42 INFO - Running setup.py (path:/tmp/pip-EruFo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:22:42 INFO - Running setup.py (path:/tmp/pip-EUw5WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:22:42 INFO - Running setup.py (path:/tmp/pip-tS4K1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:22:42 INFO - Running setup.py (path:/tmp/pip-bhMR6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:22:42 INFO - Running setup.py (path:/tmp/pip-DZivpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:22:42 INFO - Running setup.py (path:/tmp/pip-D30TmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:22:42 INFO - Running setup.py (path:/tmp/pip-_KQ0c1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:22:42 INFO - Running setup.py (path:/tmp/pip-EhuQpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:22:42 INFO - Running setup.py (path:/tmp/pip-EBviUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:22:43 INFO - Running setup.py (path:/tmp/pip-8YupAc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:22:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:22:43 INFO - Running setup.py install for manifestparser 17:22:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Running setup.py install for mozcrash 17:22:43 INFO - Running setup.py install for mozdebug 17:22:43 INFO - Running setup.py install for mozdevice 17:22:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Running setup.py install for mozfile 17:22:43 INFO - Running setup.py install for mozhttpd 17:22:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Running setup.py install for mozinfo 17:22:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Running setup.py install for mozInstall 17:22:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:22:43 INFO - Running setup.py install for mozleak 17:22:43 INFO - Running setup.py install for mozlog 17:22:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Running setup.py install for moznetwork 17:22:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Running setup.py install for mozprocess 17:22:44 INFO - Running setup.py install for mozprofile 17:22:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Running setup.py install for mozrunner 17:22:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Running setup.py install for mozscreenshot 17:22:44 INFO - Running setup.py install for moztest 17:22:44 INFO - Running setup.py install for mozversion 17:22:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:22:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:22:44 INFO - Cleaning up... 17:22:44 INFO - Return code: 0 17:22:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:22:44 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')]} 17:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:44 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')]} 17:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:44 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:44 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 17:22:44 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 17:22:44 INFO - Using env: {'DISPLAY': ':0', 17:22:44 INFO - 'HOME': '/home/cltbld', 17:22:44 INFO - 'LANG': 'en_US.UTF-8', 17:22:44 INFO - 'LANGUAGE': 'en_US:en', 17:22:44 INFO - 'LOGNAME': 'cltbld', 17:22:44 INFO - 'MAIL': '/var/mail/cltbld', 17:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:44 INFO - 'MOZ_NO_REMOTE': '1', 17:22:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:44 INFO - 'NO_EM_RESTART': '1', 17:22:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:44 INFO - 'PWD': '/builds/slave/test', 17:22:44 INFO - 'SHELL': '/bin/bash', 17:22:44 INFO - 'SHLVL': '1', 17:22:44 INFO - 'TERM': 'linux', 17:22:44 INFO - 'TMOUT': '86400', 17:22:44 INFO - 'USER': 'cltbld', 17:22:44 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:44 INFO - '_': '/tools/buildbot/bin/python'} 17:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:22:45 INFO - Running setup.py (path:/tmp/pip-A3P0Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:22:45 INFO - Running setup.py (path:/tmp/pip-g4qa7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:22:45 INFO - Running setup.py (path:/tmp/pip-v3mhkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:22:45 INFO - Running setup.py (path:/tmp/pip-UxkNQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:22:45 INFO - Running setup.py (path:/tmp/pip-HJoZG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:22:45 INFO - Running setup.py (path:/tmp/pip-gZgPKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:22:45 INFO - Running setup.py (path:/tmp/pip-0M8b_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:22:45 INFO - Running setup.py (path:/tmp/pip-L0BVIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:22:45 INFO - Running setup.py (path:/tmp/pip-PePR59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:22:45 INFO - Running setup.py (path:/tmp/pip-enHS4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:22:45 INFO - Running setup.py (path:/tmp/pip-vOlgsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:22:45 INFO - Running setup.py (path:/tmp/pip-0Qd0sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:22:45 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)) 17:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:22:45 INFO - Running setup.py (path:/tmp/pip-If_2bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:22:46 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)) 17:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:22:46 INFO - Running setup.py (path:/tmp/pip-q5Fhuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:22:46 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)) 17:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:22:46 INFO - Running setup.py (path:/tmp/pip-F2dxY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:22:46 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)) 17:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:22:46 INFO - Running setup.py (path:/tmp/pip-EVR0_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:22:46 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)) 17:22:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:22:46 INFO - Running setup.py (path:/tmp/pip-0V_MV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:22:46 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)) 17:22:46 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)) 17:22:46 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)) 17:22:46 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)) 17:22:46 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)) 17:22:46 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)) 17:22:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:48 INFO - Downloading blessings-1.5.1.tar.gz 17:22:48 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 17:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:22:48 INFO - Installing collected packages: blessings 17:22:48 INFO - Running setup.py install for blessings 17:22:48 INFO - Successfully installed blessings 17:22:48 INFO - Cleaning up... 17:22:48 INFO - Return code: 0 17:22:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:22:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:22:48 INFO - Reading from file tmpfile_stdout 17:22:48 INFO - Current package versions: 17:22:48 INFO - argparse == 1.2.1 17:22:48 INFO - blessings == 1.5.1 17:22:48 INFO - blobuploader == 1.2.4 17:22:48 INFO - docopt == 0.6.1 17:22:48 INFO - manifestparser == 1.1 17:22:48 INFO - mozInstall == 1.12 17:22:48 INFO - mozcrash == 0.16 17:22:48 INFO - mozdebug == 0.1 17:22:48 INFO - mozdevice == 0.47 17:22:48 INFO - mozfile == 1.2 17:22:48 INFO - mozhttpd == 0.7 17:22:48 INFO - mozinfo == 0.9 17:22:48 INFO - mozleak == 0.1 17:22:48 INFO - mozlog == 3.1 17:22:48 INFO - moznetwork == 0.27 17:22:48 INFO - mozprocess == 0.22 17:22:48 INFO - mozprofile == 0.27 17:22:48 INFO - mozrunner == 6.11 17:22:48 INFO - mozscreenshot == 0.1 17:22:48 INFO - mozsystemmonitor == 0.0 17:22:48 INFO - moztest == 0.7 17:22:48 INFO - mozversion == 1.4 17:22:48 INFO - psutil == 3.1.1 17:22:48 INFO - requests == 1.2.3 17:22:48 INFO - wsgiref == 0.1.2 17:22:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:22:48 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')]} 17:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:48 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')]} 17:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:48 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:48 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 17:22:48 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 17:22:48 INFO - Using env: {'DISPLAY': ':0', 17:22:48 INFO - 'HOME': '/home/cltbld', 17:22:48 INFO - 'LANG': 'en_US.UTF-8', 17:22:48 INFO - 'LANGUAGE': 'en_US:en', 17:22:48 INFO - 'LOGNAME': 'cltbld', 17:22:48 INFO - 'MAIL': '/var/mail/cltbld', 17:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:48 INFO - 'MOZ_NO_REMOTE': '1', 17:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:48 INFO - 'NO_EM_RESTART': '1', 17:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:48 INFO - 'PWD': '/builds/slave/test', 17:22:48 INFO - 'SHELL': '/bin/bash', 17:22:48 INFO - 'SHLVL': '1', 17:22:48 INFO - 'TERM': 'linux', 17:22:48 INFO - 'TMOUT': '86400', 17:22:48 INFO - 'USER': 'cltbld', 17:22:48 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:48 INFO - '_': '/tools/buildbot/bin/python'} 17:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 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)) 17:22:48 INFO - Cleaning up... 17:22:48 INFO - Return code: 0 17:22:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:22:48 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')]} 17:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:48 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')]} 17:22:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:48 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 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:48 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 17:22:48 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 17:22:48 INFO - Using env: {'DISPLAY': ':0', 17:22:48 INFO - 'HOME': '/home/cltbld', 17:22:48 INFO - 'LANG': 'en_US.UTF-8', 17:22:48 INFO - 'LANGUAGE': 'en_US:en', 17:22:48 INFO - 'LOGNAME': 'cltbld', 17:22:48 INFO - 'MAIL': '/var/mail/cltbld', 17:22:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:48 INFO - 'MOZ_NO_REMOTE': '1', 17:22:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:48 INFO - 'NO_EM_RESTART': '1', 17:22:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:48 INFO - 'PWD': '/builds/slave/test', 17:22:48 INFO - 'SHELL': '/bin/bash', 17:22:48 INFO - 'SHLVL': '1', 17:22:48 INFO - 'TERM': 'linux', 17:22:48 INFO - 'TMOUT': '86400', 17:22:48 INFO - 'USER': 'cltbld', 17:22:48 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:48 INFO - '_': '/tools/buildbot/bin/python'} 17:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:48 INFO - Downloading/unpacking jsonschema 17:22:48 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 17:22:48 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 17:22:48 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 17:22:48 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 17:22:48 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 17:22:48 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 17:22:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:22:50 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 17:22:50 INFO - Downloading/unpacking functools32 (from jsonschema) 17:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:50 INFO - Downloading functools32-3.2.3-2.tar.gz 17:22:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:22:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:22:50 INFO - warning: no files found matching '*.txt' 17:22:50 INFO - no previously-included directories found matching 'build' 17:22:50 INFO - no previously-included directories found matching 'dist' 17:22:50 INFO - no previously-included directories found matching '.git*' 17:22:50 INFO - Installing collected packages: jsonschema, functools32 17:22:50 INFO - Running setup.py install for functools32 17:22:50 INFO - warning: no files found matching '*.txt' 17:22:50 INFO - no previously-included directories found matching 'build' 17:22:50 INFO - no previously-included directories found matching 'dist' 17:22:50 INFO - no previously-included directories found matching '.git*' 17:22:50 INFO - Successfully installed jsonschema functools32 17:22:50 INFO - Cleaning up... 17:22:51 INFO - Return code: 0 17:22:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:22:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:22:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:22:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f275860bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24bf240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, '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': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', '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 17:22:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:22:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:22:51 INFO - Using env: {'DISPLAY': ':0', 17:22:51 INFO - 'HOME': '/home/cltbld', 17:22:51 INFO - 'LANG': 'en_US.UTF-8', 17:22:51 INFO - 'LANGUAGE': 'en_US:en', 17:22:51 INFO - 'LOGNAME': 'cltbld', 17:22:51 INFO - 'MAIL': '/var/mail/cltbld', 17:22:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:22:51 INFO - 'MOZ_NO_REMOTE': '1', 17:22:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:22:51 INFO - 'NO_EM_RESTART': '1', 17:22:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:22:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:22:51 INFO - 'PWD': '/builds/slave/test', 17:22:51 INFO - 'SHELL': '/bin/bash', 17:22:51 INFO - 'SHLVL': '1', 17:22:51 INFO - 'TERM': 'linux', 17:22:51 INFO - 'TMOUT': '86400', 17:22:51 INFO - 'USER': 'cltbld', 17:22:51 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:22:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:22:51 INFO - '_': '/tools/buildbot/bin/python'} 17:22:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:22:51 INFO - Downloading/unpacking flake8 17:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:22:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 17:22:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:22:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 17:22:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 17:22:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:22:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:22:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:22:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:22:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:22:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:22:53 INFO - no previously-included directories found matching 'docs/_build' 17:22:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:22:53 INFO - Running setup.py install for pep8 17:22:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:22:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:22:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:22:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:22:53 INFO - no previously-included directories found matching 'docs/_build' 17:22:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:22:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:22:53 INFO - Cleaning up... 17:22:53 INFO - Return code: 0 17:22:53 INFO - Running post-action listener: _resource_record_post_action 17:22:53 INFO - Running post-action listener: _start_resource_monitoring 17:22:53 INFO - Starting resource monitoring. 17:22:53 INFO - ##### 17:22:53 INFO - ##### Running install step. 17:22:53 INFO - ##### 17:22:53 INFO - Running pre-action listener: _resource_record_pre_action 17:22:53 INFO - Running main action method: install 17:22:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:22:54 INFO - Reading from file tmpfile_stdout 17:22:54 INFO - Detecting whether we're running mozinstall >=1.0... 17:22:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:22:54 INFO - Reading from file tmpfile_stdout 17:22:54 INFO - Output received: 17:22:54 INFO - Usage: mozinstall [options] installer 17:22:54 INFO - Options: 17:22:54 INFO - -h, --help show this help message and exit 17:22:54 INFO - -d DEST, --destination=DEST 17:22:54 INFO - Directory to install application into. [default: 17:22:54 INFO - "/builds/slave/test"] 17:22:54 INFO - --app=APP Application being installed. [default: firefox] 17:22:54 INFO - mkdir: /builds/slave/test/build/application 17:22:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:22:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:23:08 INFO - Reading from file tmpfile_stdout 17:23:08 INFO - Output received: 17:23:08 INFO - /builds/slave/test/build/application/firefox/firefox 17:23:08 INFO - Running post-action listener: _resource_record_post_action 17:23:08 INFO - ##### 17:23:08 INFO - ##### Running run-tests step. 17:23:08 INFO - ##### 17:23:08 INFO - Running pre-action listener: _resource_record_pre_action 17:23:08 INFO - Running main action method: run_tests 17:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:23:08 INFO - Python 2.7.3 17:23:08 INFO - Return code: 0 17:23:08 INFO - grabbing minidump binary from tooltool 17:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:08 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 0x254e0b0>, '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 0x254efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x254f440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:23:08 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 17:23:08 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 17:23:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:23:08 INFO - Return code: 0 17:23:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:23:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:23:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:23:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:23:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:23:09 INFO - Return code: 0 17:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost'] in /builds/slave/test/build 17:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost 17:23:09 INFO - Using env: {'DISPLAY': ':0', 17:23:09 INFO - 'HOME': '/home/cltbld', 17:23:09 INFO - 'LANG': 'en_US.UTF-8', 17:23:09 INFO - 'LANGUAGE': 'en_US:en', 17:23:09 INFO - 'LOGNAME': 'cltbld', 17:23:09 INFO - 'MAIL': '/var/mail/cltbld', 17:23:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:23:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:09 INFO - 'MOZ_NO_REMOTE': '1', 17:23:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:23:09 INFO - 'NO_EM_RESTART': '1', 17:23:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:09 INFO - 'PWD': '/builds/slave/test', 17:23:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:23:09 INFO - 'SHELL': '/bin/bash', 17:23:09 INFO - 'SHLVL': '1', 17:23:09 INFO - 'TERM': 'linux', 17:23:09 INFO - 'TMOUT': '86400', 17:23:09 INFO - 'USER': 'cltbld', 17:23:09 INFO - 'XDG_SESSION_COOKIE': '6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260', 17:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:09 INFO - '_': '/tools/buildbot/bin/python'} 17:23:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost'] with output_timeout 3600 17:23:09 INFO - mozversion INFO | application_buildid: 20151214160119 17:23:09 INFO - mozversion INFO | application_changeset: ae37fdb042c07c0cb9d0afcd41372a96454f4f4f 17:23:09 INFO - mozversion INFO | application_display_name: Nightly 17:23:09 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:23:09 INFO - mozversion INFO | application_name: Firefox 17:23:09 INFO - mozversion INFO | application_remotingname: firefox 17:23:09 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 17:23:09 INFO - mozversion INFO | application_vendor: Mozilla 17:23:09 INFO - mozversion INFO | application_version: 46.0a1 17:23:09 INFO - mozversion INFO | platform_buildid: 20151214160119 17:23:09 INFO - mozversion INFO | platform_changeset: ae37fdb042c07c0cb9d0afcd41372a96454f4f4f 17:23:09 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 17:23:09 INFO - mozversion INFO | platform_version: 46.0a1 17:23:09 INFO - 2015-12-14 17:23:09,870 DEBUG : using testdate: 1450142589 17:23:09 INFO - 2015-12-14 17:23:09,870 DEBUG : actual date: 1450142589 17:23:09 INFO - 2015-12-14 17:23:09,879 INFO : starting webserver on 'localhost:42223' 17:23:09 INFO - 2015-12-14 17:23:09,879 INFO : Starting test suite talos-linux64-ix-018 17:23:09 INFO - 2015-12-14 17:23:09,879 INFO : Starting test dromaeo_css 17:23:09 INFO - 2015-12-14 17:23:09,880 DEBUG : operating with platform_type : linux_ 17:23:09 INFO - 2015-12-14 17:23:09,880 INFO : Initialising browser for dromaeo_css test... 17:23:09 INFO - 2015-12-14 17:23:09,894 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfJ7pyR/profile http://localhost:42223/getInfo.html 17:23:13 INFO - 2015-12-14 17:23:13,867 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:23:13 INFO - 2015-12-14 17:23:13,868 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:23:13 INFO - 2015-12-14 17:23:13,868 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:23:13 INFO - 2015-12-14 17:23:13,868 DEBUG : BROWSER_OUTPUT: __metrics 17:23:13 INFO - 2015-12-14 17:23:13,996 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:23:14 INFO - 2015-12-14 17:23:14,052 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] 17:23:15 INFO - 2015-12-14 17:23:15,063 INFO : Browser initialized. 17:23:15 INFO - 2015-12-14 17:23:15,063 INFO : Running cycle 1/1 for dromaeo_css test... 17:23:15 INFO - 2015-12-14 17:23:15,064 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfJ7pyR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:23:27 INFO - 2015-12-14 17:23:27,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 142704640 17:23:27 INFO - 2015-12-14 17:23:27,098 DEBUG : BROWSER_OUTPUT: 17:24:18 INFO - 2015-12-14 17:24:18,391 DEBUG : BROWSER_OUTPUT: 1450142658390 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] 17:26:30 INFO - 2015-12-14 17:26:30,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42223/tests/dromaeo/cssquery-ext.html) 17:26:31 INFO - 2015-12-14 17:26:31,590 DEBUG : BROWSER_OUTPUT: RSS: Main: 248836096 17:26:31 INFO - 2015-12-14 17:26:31,590 DEBUG : BROWSER_OUTPUT: 17:29:34 INFO - 2015-12-14 17:29:34,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-ext.html (next: http://localhost:42223/tests/dromaeo/cssquery-jquery.html) 17:29:34 INFO - 2015-12-14 17:29:34,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 223440896 17:29:34 INFO - 2015-12-14 17:29:34,617 DEBUG : BROWSER_OUTPUT: 17:32:36 INFO - 2015-12-14 17:32:36,691 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42223/tests/dromaeo/cssquery-mootools.html) 17:32:37 INFO - 2015-12-14 17:32:37,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 207380480 17:32:37 INFO - 2015-12-14 17:32:37,260 DEBUG : BROWSER_OUTPUT: 17:35:39 INFO - 2015-12-14 17:35:39,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42223/tests/dromaeo/cssquery-prototype.html) 17:35:39 INFO - 2015-12-14 17:35:39,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 201461760 17:35:39 INFO - 2015-12-14 17:35:39,800 DEBUG : BROWSER_OUTPUT: 17:38:42 INFO - 2015-12-14 17:38:42,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42223/tests/dromaeo/cssquery-yui.html) 17:38:43 INFO - 2015-12-14 17:38:43,248 DEBUG : BROWSER_OUTPUT: RSS: Main: 207052800 17:38:43 INFO - 2015-12-14 17:38:43,248 DEBUG : BROWSER_OUTPUT: 17:41:45 INFO - 2015-12-14 17:41:45,742 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/dromaeo/cssquery-yui.html (next: http://localhost:42223/tests/dromaeo/cssquery-dojo.html) 17:41:45 INFO - 2015-12-14 17:41:45,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 249421824 17:41:45 INFO - 2015-12-14 17:41:45,993 DEBUG : BROWSER_OUTPUT: 17:41:45 INFO - 2015-12-14 17:41:45,994 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:41:45 INFO - 2015-12-14 17:41:45,994 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:41:45 INFO - 2015-12-14 17:41:45,994 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:41:45 INFO - 2015-12-14 17:41:45,994 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:41:45 INFO - 2015-12-14 17:41:45,996 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2635.1040646180295;2687.9731202687967;2690.1794952539467;2697.757201851833;2716.891324347026;4566.223741963866;4694.85915422537;4761.40482439695;4785.258284965831;4789.23372260438;4628.444586649607;4661.953380466194;4712.3638308828295;4715.646326525507;4793.281007848825;4342.109867477176;4364.869053928387;4389.758563279019;4503.347014682885;4508.77456127194;4428.667849911253;4474.910501789962;4497.505274419811;4498.595126438619;4533.659975501851;4219.050713589449;4261.659067274611;4313.137372525507;4313.633341166004;4373.562643735633;4379.956200437987;4393.714408563433;4394.384786129945;4402.691811573191;4446.155230506193;4363.1273745250755;4382.364557139229;4389.561043895616;4411.382406463098;4417.668674849367;4526.818927242906;4590.495045544987;4642.14120387726;4644.674872758908;4722.83470078549;5107.182850743863;5123.180291153435;5126.256692779526;5127.0274722806025;5130.897382052375;5129.358830146231;5151.072806894757;5153.9742301288625;5156.948430515685;5196.714180720059;4616.6537509686905;4692.272697731857;4753.714777113384;4754.738489383117;4816.662833601685;5220.895582088337;5258.053550360972;5262.868428289324;5266.0257852297445;5312.840614781563;5032.043911656773;5048.798048078109;5054.949450505448;5057.747112644353;5096.235564665256;4789.042191561702;4916.262560615936;4921.8523444296725;4936.062148191832;5031.77357018935;5034.144195486776;5046.9747651261505;5051.090803655304;5052.9242061369105;5104.668196567211;4996.6752161110135;5003.224500202475;5010.724410157476;5024.648274620741;5026.447090820006;4023.3562629979065;4106.856260030884;4109.4246805447265;4149.481314835645;4245.830166793356;3701.444783282529;3719.1445967427644;3719.6094410087094;3757.755745876509;3758.699304055669;4356.978215108904;4400.141972315432;4405.140997505456;4431.578999994996;4442;4764.023375208074;4874.2201247800185;4877.170880950176;4935.235038569028;5079.517445842639;4400.119976004812;4405.625521830616;4460.955390446119;4469.173202957463;4478.350639157304;4500.572445617661;4518.051209246095;4537.954620453753;4538.251188553918;4574.588287054181;4592.47186573535;4701.647376446712;4703.929441058455;4868.95131048692;4870.26945958102;4964.304997300309;4978.850634480971;5037.471065538066;5038.496150384932;5083.872903177302;4807.879803004953;4821.686590371614;4824.734639594856;4827.155247831685;4863.683860549053;4763.880902977453;4866.48901865299;4871.171900776947;5020.748962551785;5060.139776237975;4930.087933732271;4999.0501804657;4999.525045120708;4999.875003124951;5055.797768089383;4841.322214889989;4960.77676504551;4972.7762250699525;4973.502649734997;5015.423226328873;4727.929081063856;4836.685615434985;4839.516048395132;4945.8021679132435;5023.422306434807;4768.809247630056;4854.80580776765;4872.731999739906;4895.216765317532;4920.803167873388;4977.552020318189;4983.352164218773;4990.72551009698;4996.900061998813;5037.471065538066;4641.74470404131;4875.585575226146;4884.194107972145;4885.1450996076255;4887.90224195507 17:41:46 INFO - 2015-12-14 17:41:45,999 DEBUG : BROWSER_OUTPUT: |1;ext.html;565.1615453018477;571.0321005895009;580.7909152705026;594.6372712645287;596.8776400837826;27731;35557;36442.8177859111;36474;36477;40743.59256407435;41190.794046029805;41292;41292.17415651685;41304.79347603258;7020.684069216872;7148;7148.678309476086;7721.652525636346;7873.370130389555;10263.640772572962;10492.527836247387;10514.894851051506;10516.053555180033;10532.47337633119;10154.086132248096;10242.487875606228;10272.691819245434;10273.691789246297;10277.794444111114;7136.607486588262;7338.926610733931;7342.669579868865;7369.4841361104745;9977.401355918668;7081.645917704094;9914.9504252479;10111.191104711605;10132.645357412455;10136.391816491034;34306.71166104237;34515.482267766005;34681.65318346835;34710;34757.13107172315;3020.6677265500775;3038.9439074828183;3084.9973758528345;3104.4567200740007;3555.5911070227;28342.716572834215;28371.57442638362;28615.856920715472;28697.139085827457;28942;3334.6498617645284;3425.5718035245595;3437.3984552703178;3477.5131481592343;3565.5899571549085;207221;209319;209387;210397.9480102605;210597;51485.7425712869;53364.733176334645;57008.42991570114;62703.37296627067;64306;55685.164722529196;55986.72006640022;56056.439435605935;56284;56396;12855.29295888735;13046.282454465096;13046.804297935538;13066.608001759962;13068.085234033526;9750.95124524387;9826.459544724965;9840.606375745034;9850.310478266596;9985.151262142654;19402.902985484983;19542.413727588195;19542.609147816966;19698.606027879363;19783.40649780509;7607;7811.101723301779;7900.430196711305;7913.683452661944;7928.4450088494405;824.7484517222305;874.6632546469655;889.915458031486;918.4535201555065;989.8861630912393;26815.86592067066;27230.72769272282;29756.10731678053;31173.688263117532;31444;944.593824655391;954.8997355277735;965.560669895196;1024.3137098144261;1067.6263307842194;13808.723825523433;13887.305634718223;13930.16419014842;14060.648483787783;14068.507602233873;305751;310505;310543;310822;311929;47166.76416617895;47663.28505072427;47698.5230147701;47714;47764.5223547767;1203.8435003449495;1217.3000524698334;1219.804831227017;1221.3343727668441;1242.0808601634906;23564.646530301718;29359.853200733858;30940.226494336923;31193;31276.218094547818;43735.34396984112;44414.77792611016;44428;44472.11055778931;44478;238787;241863;241870;241874;242512;34713.479297810045;41922.58077419187;42304;42593.36109958291;43366.566334336254;38654.80672596731;38822.223555529294;38885.6111438882;38921.221575568896;39376.606233937295;32395;32837.17907052343;33302;33712.49431258484;33777.83111084429;33091.66908330886;33628;33724.831375842965;33788.662113379534;33944.490832638025;33642.299347072294;33870.66129338675;38820.6117938817;39598.80200599094;39615.80192099021;28770.280742980754;30288.394232115676;30430.695693042788;30664.693353066184;30851.845740771154;23824.642630360213;24176.274711758677;25157;25285.241442756746;25545.61681574815 17:41:46 INFO - 2015-12-14 17:41:45,999 DEBUG : BROWSER_OUTPUT: |2;jquery.html;331.8622771549807;332.35025525098433;333.19034745568257;334.49324273725296;336.40792205717946;12676.746465070693;13098.969659702761;15176.696466070673;15561.144137072471;16055.83944160558;9385.436873787561;9671.081247281514;9699.175570076552;13713.794293085612;14086.788698169536;4433.246348120819;4628.953710462886;4630.606398456135;4630.791614377345;4632.884177895563;3547;4879.536444037829;5282.31329927109;5332.92000619991;5334.199870019509;5241.161414173733;5279.524842764151;5288.576913846883;5293.735313234342;5297.020051290504;5186.3257776489;5335.866603334909;5336.17289320154;5336.546393556552;5337.306150200449;5124.641275110744;5138.049460849755;5138.486151384869;5141.845744627667;5215;11577.594784182513;11922.880771192264;11997.740237275048;11998.340091294975;12026.037916966621;1677.2955358749232;1712.700277451441;1748.9825101748947;1763.9118044097743;1787.7932395632895;8806.559672016374;8820.073892241286;8912.509811960339;8916.331275154389;9135.406198597137;808.9838203235902;1782.3976993589245;1787.9553011174694;1807.656545256397;1847.7782666080022;54523.45476545223;56581.43418565844;57153.85692286173;57875;59132.11301830476;10002.849957250646;10047.447390393452;10048.648297309559;10076.74808129788;10101.393916365041;12505.874941250471;12517.874821251853;12523.624291271277;12532.937335313265;12542;6632.801015969432;6675.132232809704;6679.799606011828;6727.159105111861;6732.461403087741;6225.252969643581;6410.391012853772;6434.806955791334;6444.162258906312;6447.25856526496;4198.202341555095;4202.768847713405;4216.51510076339;4278.4010238566625;4316.201502722007;1577.1798664694295;1620.8136064352514;1654.8924319919167;1669.6159883226926;1687.4684474390529;371.77321833681435;373.79254513744587;389.06235971309576;831.6423937706844;859.879616853641;8494.405391622528;9443.811123777487;9851.605935762489;10106.191504679608;10179.134773544183;397.7891717389788;400.709485622921;403.7012610668143;404.82997141200764;407.44790808454115;5089.211172268304;5680.857978550486;5721.5708821838525;5741.45456181662;5776.740046697909;512251;514461;514548;529123;532916;74792.25207747961;75318.62340688262;75788.24211757812;75837;76069.61965190139;436.75541696649975;438.6425063573241;441.111161010564;442.48229571400896;442.48450555103585;9618.951905240709;9820.116189542976;9820.607175712892;9823.950880245553;9920.851187232052;33648.663513364554;33859.66140338565;34679.30641387209;35313.47029794606;35558;45413.00456949799;46868.531314686414;48626.27060594023;49016;49445.50554494409;8500.53247071417;8817.603207855791;8941.37410381293;8957.372983891066;8968.820623587622;10354.378737275787;10377.16982641402;10388.896111038794;15935.21133801557;17113.828861711223;8428.410011299151;8753;8765.430247033923;8932.955335223282;9527.142557169887;7699.345555627834;8996.685116020908;9291.907080929104;9353.85969210476;9556.85664715016;9551.761205969906;9740.172085372822;9831.606735730491;10099.646512372326;10101.191904647463;14068.859311406755;14091.929540352232;14318.212498312692;14332.355044022836;14644.926775366055;15084.39662413535;15253.08481491114;15337.156456395005;15782.447614333443;15812.209389530248 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1234.3704710597592;1648.9752653710195;1696.1264948551498;1715.6054107555262;1735.4446577095327;6247.968760156198;7128;7235.782926512213;7298.233685463017;7325.157606875217;7220.458465615086;7361.263873612636;7408.777736667895;7414.888776668341;7420.962895185517;1748.9038102904335;1788.0255260882784;1790.7045337519294;2100.747910250768;2137.6152292587326;1779.4483710049926;1793.417139429684;2009.2264478175862;2086.8121869031784;2089.665653495441;2086.5722526882028;2091.623507768601;2093.80108889656;2095.706601075843;2096.727425434691;2046.1610739596767;2094.7695753467106;2096.0462989339812;2096.06725007371;2097.9895100524554;2048.283100914683;2073.5956488484753;2075.0351086744586;2076.4289820299387;2103.1797598936428;7190.9280907191305;7289.817754556178;7329.743458978964;7332.8900066499045;7334.743283985035;4133.111381053058;4174.457320548309;4192.706510544263;4196.181744559812;4211.263028969948;2625.566781481053;2642.6036094585775;2681.691605465377;2697.2447714639734;2707.7021527631946;854.7521218846528;857.9742607721778;894.5884892949186;897.2194191053825;913.0732306708696;9855.211583073336;9901.306908516406;9925;9946.254030947708;9952.552135153936;3922.5096862892137;3976.5824588418377;4028.879133625996;4033.556308806029;4038.273110840018;8541.871871921927;8546.444481108709;8549.27331176857;8562.100979397199;8568.143185681507;4213.599708027732;4216.704830661824;4219.261629214937;4219.438807641192;4220.2192594370135;3730.0488375464083;3757.887263382103;3758.1356288053903;3763.040424691741;3768.171002379472;3433.6738009889023;3447.3105378924315;3453.3611281912927;3458.75788694794;3478.860845566149;963.5760265483165;986.0287616697548;1087.6410784441116;1102.4928532874967;1106.3085571517802;811.5009269299338;811.6469335838929;812.6058861452191;852.6078004118051;930.9441433514011;6796.422304104108;6871.347222013901;6882.759103431456;6894.207166175854;6894.655267236618;1022.3201570955232;1036.6838114375037;1040.453761775074;1049.9370037797758;1060.7083052160563;5274.050670879278;5328.254044433782;5332.146856503018;5337.932413517235;5356.169793681907;8102.918970810217;8305.626246818907;8308.29379502737;8327.417080804285;8533.146685331418;8450.281726053354;8579.356548258906;8604.569771511524;8630;8646.78383040429;6859.862802744017;6935.757248496379;6959.478039147084;7018.368346848808;7071.257517960643;6700.162479690039;6758.391744742997;6760.560563563353;6764.053032575542;6770.458363330823;7799.805004874924;7891.684332626631;8037.31682806968;8053.0336359634875;8093.352531797324;7980.281774640311;8077.919220807952;8109.067457242257;8212.343012558862;8240.917590824016;6365.395287447685;6365.440467085408;6441.259255185713;6446.903296450463;6448.161738973903;5831.620944638585;6721.731130754715;6843.315668433117;6843.315668433117;6862.588244705334;5870.0607902735355;6170.444659980623;6318.494520438446;6395.808125756234;6532.314107018695;6472.676366181766;6485.254195767548;6491.902621460587;6573.375529324707;6576.408123268929;6363.7772677956045;6396.072569477493;6434.967825160844;6435.871282574416;6462.386073323027;6071.2714474263375;6086.7260973255425;6091.086337049478;6100.664463454553;6112.633242005494;6094.634321940698;6120.846978825387;6123.050927106306;6128.417800308964;6134.263888333428 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: |4;prototype.html;301.7736697476892;308.84249032993165;310.22753344173003;316.05657113516133;318.8310195596335;5314.92027619585;5362.8123015694455;5396.541293990011;5456.181572764087;5530.5299049580735;5228.79084836606;5391.892162156764;5393.083175860103;5398.838034858951;5403.2705584746145;3952.664023558001;4197.097624010837;4198.265303571863;4203.138356636898;4206.852760153403;4038.5759495253014;4055.5538890721996;4170.893698682161;4172.853950111747;4176.436181115541;4104.035551645361;4113.773742444165;4114.567970363114;4123.505179378479;4128.772917489537;4113.259613269625;4115.814788334532;4123.092919557693;4126.773027483488;4151.709380343377;3599.8200089995394;3599.8200089995394;3600.47793070006;3606.7114630829733;3609.2781443711365;5128.589712822965;5224.92162617561;5238.345206849144;5283.022640811462;5308.309919710451;4788.9521104788855;4789.257665061921;4791.616670666338;4793.161196790547;4795.2807078938085;5473.0969390050595;5746.827595172152;5758.510526605243;5761.279840019997;5764.7117644117625;3840.1935593525395;3861.1119442528093;3889.358255887775;3905.2384784966944;3911.100446897201;5923.052311630162;5996.19051428056;6006.609570377956;6017.308009578867;6023.367546407651;4049.7367671101283;4052.4326594276827;4053.3717273822604;4055.2497787909333;4055.3105971984833;4096.242195193899;4101.97949010259;4102.077032667626;4102.323116685773;4109.97945010273;4591.265397536445;4722.008378240539;4726.409198850144;4727.858164255078;4729.668923175344;5706.3152421709665;5728.083506638918;5729;5730.684812335278;5731.77072917087;1440.6470414748453;1464.868161865416;1466.0836976889443;1466.3108339080616;1468;983.5180761426992;984.8165853416774;986.2603047714215;990.3958585262984;1004.0311099788739;5050.772715227824;5051.166557518042;5055.469175736568;5058.190689489664;5070.974645126751;5952;5952.136940143655;5952.553558483044;5956.583039187215;5957.493613042853;5041.395032596112;5046.62150338726;5053.444121146671;5054.519820617109;5056.494350564988;3143.0570828751283;3188.2029492626843;3221.291315910496;3226.209578653245;3227.9677203227666;5589.413111623302;5605.299337582802;5607.130894711327;5609.6353737007785;5610.663360198401;6092.908606370908;6094.988653744343;6098.908516372258;6104.389561043949;6110.633361998294;5468.699221542854;5592.496675299161;5596.916046259309;5603.663780173202;5606.579506537026;5906.822795316148;5935.643861368331;5937.495312898452;5939.227900372924;5942.494887934401;5575.578227551949;5756.712164391847;5757.0788673811985;5762.308522977102;5766.048601980626;5481.506664400223;5550.028744969695;5646.887062258814;5658.5473162147755;5659.434056594308;4075.633193012643;4082.3058430750907;4101.979490102531;4122.732022418533;4123.948393159725;5790.218320526844;5928.199693041384;5975.073863551156;5993.640381577119;5995.340512543704;5717.8570535736935;5775.711214439345;5777.480026797609;5780.5953583248765;5783.987802134694;5770.076787713946;5772.9711351442975;5775.768969241183;5775.826725198251;5784.768609255582;5479.150731636602;5655.575831812713;5659.603827732019;5660.801871934463;5663.57523185771;5805.506531944832;5808.39011903744;5811.4188581143235;5811.476967072984;5819.94180058194;5665.971670141787;5666.405027472221;5668.971655141698;5669.092945128759;5686.459786320293 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: |5;yui.html;649.9285078641349;715.8711431942252;728.3481284250596;772.1930582541244;800.4996876951905;11244.100471962243;11577.884221157796;11581.305121692694;11581.36302503362;11583.594574189905;11533;11638.709032274177;11741.354225517593;11751.001164900994;11751.471183796728;803.0764620686214;820.1716266570747;821.7534739578133;821.7822277096574;822.042320696388;803.5138741061661;810.5136917849272;810.712197170005;811.8903947967037;812.1229072601576;852.6802449081595;860.513809697522;861.1216559109704;861.2205953611959;862.2756884217256;843.3969711656165;847.7965288330818;849.2314455417826;850.7107583421666;851.7742798158492;631.343402861024;631.5105793010408;631.671530803984;631.974721011159;632.0045927663948;10552.47237638115;10579.629712960086;10668.039876411083;10683.09193718527;10709.464526773709;9696.806063878683;9799.853002204973;9899.208063354914;10091.444970526618;10322.48387580618;8523.062463129112;8610.311175105977;8721.389502734812;8881.06748791374;8920.598573064228;3049.451098802215;3099.02380750063;3116.688331166888;3164.0507847645845;3240.91897702557;33987.83006084978;34583.6541634583;38153.618463815284;42063.36904946428;43078;8808;9077.500737459372;9265.44407335562;9289.349745517884;9941.502924853728;15522.22388880574;15794.68410631804;15795.526134215992;15798.36806527726;15805.36778528869;6400.359964003563;6458.225012998376;6489.480841532761;6550.181227346582;6670.699818508082;3566.233259849145;3645.9453108203397;3647.2471636388864;3647.4893514907935;3705.8332375043187;2553.1319351420607;2560.5775047117017;2594.714581396045;2595.7014943281765;2597.1689059500777;494.9951598255547;495.77690039481655;497.0258293744229;498.68831980012493;500.1622282676502;477.6656340561621;543.7471575717232;561.6405500479697;573.0973716396692;600.0129786501182;8090.595470226465;8660.566971651519;8958.955205223932;9020.81958360829;9183.724488265363;539.54947618738;551.7572268202058;605.7062324772481;618.8174488525896;626.0671599316985;3706.7961262130843;3944.8422063117155;4009.3985902114914;4026.838926442968;4031.7177797554477;49721.25418118731;50151.74924125356;50267.74866125646;50278.49721502811;50418;44044.33933491;44539.554604453544;44777;45053.32420013702;45163.32255016177;34430.13924651904;35102.824485877405;40899.182016359504;46451.30323045157;46657.30014049792;7876.763697089096;8195.180481951757;8219.383546233938;8289.502629842229;8548.316134709332;32213.194670133435;32307.838460807547;32553.511697324084;33056.1735956603;33106;37103.81448092742;40925.795371022956;41002;41046.58953410428;41544.37683434811;9309.255259579082;9630.855537166808;9928.652497162564;10147.391156530632;10241.74395640115;10618.84071738909;11312.773744524899;11341.943290283825;11368.886311136783;11412.087033037504;10051.899481005097;10542.472876355998;10698.304610200312;10800.405977671304;11227.21409501327;10211.783288264172;10609.522571484147;10860.619878304222;10929.781404372026;11017.338959662446;10155.89844101579;10219.080282774587;10457.895421045692;10724.839127413252;10728.785424291627;8937.687180948895;9157.862632060658;9199.218066464426;9318;9321.766955826159;8791.428557143765;9099.727008189764;9103.544822758704;9119.726408207764;9290.396124251902 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,989 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: __startTimestamp1450143705992__endTimestamp 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,003 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:41:46 INFO - 2015-12-14 17:41:46,004 DEBUG : BROWSER_OUTPUT: Number of tests: 6 17:41:46 INFO - 2015-12-14 17:41:46,004 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4673.73 Median:4793.28 stddev:484.56 (10.1%) stddev-sans-first:458.90 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: Values: 2635.1 2688.0 2690.2 2697.8 2716.9 4566.2 4694.9 4761.4 4785.3 4789.2 4628.4 4662.0 4712.4 4715.6 4793.3 4342.1 4364.9 4389.8 4503.3 4508.8 4428.7 4474.9 4497.5 4498.6 4533.7 4219.1 4261.7 4313.1 4313.6 4373.6 4380.0 4393.7 4394.4 4402.7 4446.2 4363.1 4382.4 4389.6 4411.4 4417.7 4526.8 4590.5 4642.1 4644.7 4722.8 5107.2 5123.2 5126.3 5127.0 5130.9 5129.4 5151.1 5154.0 5156.9 5196.7 4616.7 4692.3 4753.7 4754.7 4816.7 5220.9 5258.1 5262.9 5266.0 5312.8 5032.0 5048.8 5054.9 5057.7 5096.2 4789.0 4916.3 4921.9 4936.1 5031.8 5034.1 5047.0 5051.1 5052.9 5104.7 4996.7 5003.2 5010.7 5024.6 5026.4 4023.4 4106.9 4109.4 4149.5 4245.8 3701.4 3719.1 3719.6 3757.8 3758.7 4357.0 4400.1 4405.1 4431.6 4442.0 4764.0 4874.2 4877.2 4935.2 5079.5 4400.1 4405.6 4461.0 4469.2 4478.4 4500.6 4518.1 4538.0 4538.3 4574.6 4592.5 4701.6 4703.9 4869.0 4870.3 4964.3 4978.9 5037.5 5038.5 5083.9 4807.9 4821.7 4824.7 4827.2 4863.7 4763.9 4866.5 4871.2 5020.7 5060.1 4930.1 4999.1 4999.5 4999.9 5055.8 4841.3 4960.8 4972.8 4973.5 5015.4 4727.9 4836.7 4839.5 4945.8 5023.4 4768.8 4854.8 4872.7 4895.2 4920.8 4977.6 4983.4 4990.7 4996.9 5037.5 4641.7 4875.6 4884.2 4885.1 4887.9 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42296.81 Median:33829.66 stddev:67027.52 (198.1%) stddev-sans-first:67142.31 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: Values: 565.2 571.0 580.8 594.6 596.9 27731.0 35557.0 36442.8 36474.0 36477.0 40743.6 41190.8 41292.0 41292.2 41304.8 7020.7 7148.0 7148.7 7721.7 7873.4 10263.6 10492.5 10514.9 10516.1 10532.5 10154.1 10242.5 10272.7 10273.7 10277.8 7136.6 7338.9 7342.7 7369.5 9977.4 7081.6 9915.0 10111.2 10132.6 10136.4 34306.7 34515.5 34681.7 34710.0 34757.1 3020.7 3038.9 3085.0 3104.5 3555.6 28342.7 28371.6 28615.9 28697.1 28942.0 3334.6 3425.6 3437.4 3477.5 3565.6 207221.0 209319.0 209387.0 210397.9 210597.0 51485.7 53364.7 57008.4 62703.4 64306.0 55685.2 55986.7 56056.4 56284.0 56396.0 12855.3 13046.3 13046.8 13066.6 13068.1 9751.0 9826.5 9840.6 9850.3 9985.2 19402.9 19542.4 19542.6 19698.6 19783.4 7607.0 7811.1 7900.4 7913.7 7928.4 824.7 874.7 889.9 918.5 989.9 26815.9 27230.7 29756.1 31173.7 31444.0 944.6 954.9 965.6 1024.3 1067.6 13808.7 13887.3 13930.2 14060.6 14068.5 305751.0 310505.0 310543.0 310822.0 311929.0 47166.8 47663.3 47698.5 47714.0 47764.5 1203.8 1217.3 1219.8 1221.3 1242.1 23564.6 29359.9 30940.2 31193.0 31276.2 43735.3 44414.8 44428.0 44472.1 44478.0 238787.0 241863.0 241870.0 241874.0 242512.0 34713.5 41922.6 42304.0 42593.4 43366.6 38654.8 38822.2 38885.6 38921.2 39376.6 32395.0 32837.2 33302.0 33712.5 33777.8 33091.7 33628.0 33724.8 33788.7 33944.5 33642.3 33870.7 38820.6 39598.8 39615.8 28770.3 30288.4 30430.7 30664.7 30851.8 23824.6 24176.3 25157.0 25285.2 25545.6 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,005 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26622.38 Median:47747.40 stddev:85276.94 (178.6%) stddev-sans-first:85493.31 17:41:46 INFO - 2015-12-14 17:41:46,006 DEBUG : BROWSER_OUTPUT: Values: 331.9 332.4 333.2 334.5 336.4 12676.7 13099.0 15176.7 15561.1 16055.8 9385.4 9671.1 9699.2 13713.8 14086.8 4433.2 4629.0 4630.6 4630.8 4632.9 3547.0 4879.5 5282.3 5332.9 5334.2 5241.2 5279.5 5288.6 5293.7 5297.0 5186.3 5335.9 5336.2 5336.5 5337.3 5124.6 5138.0 5138.5 5141.8 5215.0 11577.6 11922.9 11997.7 11998.3 12026.0 1677.3 1712.7 1749.0 1763.9 1787.8 8806.6 8820.1 8912.5 8916.3 9135.4 809.0 1782.4 1788.0 1807.7 1847.8 54523.5 56581.4 57153.9 57875.0 59132.1 10002.8 10047.4 10048.6 10076.7 10101.4 12505.9 12517.9 12523.6 12532.9 12542.0 6632.8 6675.1 6679.8 6727.2 6732.5 6225.3 6410.4 6434.8 6444.2 6447.3 4198.2 4202.8 4216.5 4278.4 4316.2 1577.2 1620.8 1654.9 1669.6 1687.5 371.8 373.8 389.1 831.6 859.9 8494.4 9443.8 9851.6 10106.2 10179.1 397.8 400.7 403.7 404.8 407.4 5089.2 5680.9 5721.6 5741.5 5776.7 512251.0 514461.0 514548.0 529123.0 532916.0 74792.3 75318.6 75788.2 75837.0 76069.6 436.8 438.6 441.1 442.5 442.5 9619.0 9820.1 9820.6 9824.0 9920.9 33648.7 33859.7 34679.3 35313.5 35558.0 45413.0 46868.5 48626.3 49016.0 49445.5 8500.5 8817.6 8941.4 8957.4 8968.8 10354.4 10377.2 10388.9 15935.2 17113.8 8428.4 8753.0 8765.4 8933.0 9527.1 7699.3 8996.7 9291.9 9353.9 9556.9 9551.8 9740.2 9831.6 10099.6 10101.2 14068.9 14091.9 14318.2 14332.4 14644.9 15084.4 15253.1 15337.2 15782.4 15812.2 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5037.62 Median:6380.62 stddev:2649.21 (41.5%) stddev-sans-first:2641.21 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: Values: 1234.4 1649.0 1696.1 1715.6 1735.4 6248.0 7128.0 7235.8 7298.2 7325.2 7220.5 7361.3 7408.8 7414.9 7421.0 1748.9 1788.0 1790.7 2100.7 2137.6 1779.4 1793.4 2009.2 2086.8 2089.7 2086.6 2091.6 2093.8 2095.7 2096.7 2046.2 2094.8 2096.0 2096.1 2098.0 2048.3 2073.6 2075.0 2076.4 2103.2 7190.9 7289.8 7329.7 7332.9 7334.7 4133.1 4174.5 4192.7 4196.2 4211.3 2625.6 2642.6 2681.7 2697.2 2707.7 854.8 858.0 894.6 897.2 913.1 9855.2 9901.3 9925.0 9946.3 9952.6 3922.5 3976.6 4028.9 4033.6 4038.3 8541.9 8546.4 8549.3 8562.1 8568.1 4213.6 4216.7 4219.3 4219.4 4220.2 3730.0 3757.9 3758.1 3763.0 3768.2 3433.7 3447.3 3453.4 3458.8 3478.9 963.6 986.0 1087.6 1102.5 1106.3 811.5 811.6 812.6 852.6 930.9 6796.4 6871.3 6882.8 6894.2 6894.7 1022.3 1036.7 1040.5 1049.9 1060.7 5274.1 5328.3 5332.1 5337.9 5356.2 8102.9 8305.6 8308.3 8327.4 8533.1 8450.3 8579.4 8604.6 8630.0 8646.8 6859.9 6935.8 6959.5 7018.4 7071.3 6700.2 6758.4 6760.6 6764.1 6770.5 7799.8 7891.7 8037.3 8053.0 8093.4 7980.3 8077.9 8109.1 8212.3 8240.9 6365.4 6365.4 6441.3 6446.9 6448.2 5831.6 6721.7 6843.3 6843.3 6862.6 5870.1 6170.4 6318.5 6395.8 6532.3 6472.7 6485.3 6491.9 6573.4 6576.4 6363.8 6396.1 6435.0 6435.9 6462.4 6071.3 6086.7 6091.1 6100.7 6112.6 6094.6 6120.8 6123.1 6128.4 6134.3 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4731.30 Median:5394.81 stddev:1405.08 (26.0%) stddev-sans-first:1369.12 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: Values: 301.8 308.8 310.2 316.1 318.8 5314.9 5362.8 5396.5 5456.2 5530.5 5228.8 5391.9 5393.1 5398.8 5403.3 3952.7 4197.1 4198.3 4203.1 4206.9 4038.6 4055.6 4170.9 4172.9 4176.4 4104.0 4113.8 4114.6 4123.5 4128.8 4113.3 4115.8 4123.1 4126.8 4151.7 3599.8 3599.8 3600.5 3606.7 3609.3 5128.6 5224.9 5238.3 5283.0 5308.3 4789.0 4789.3 4791.6 4793.2 4795.3 5473.1 5746.8 5758.5 5761.3 5764.7 3840.2 3861.1 3889.4 3905.2 3911.1 5923.1 5996.2 6006.6 6017.3 6023.4 4049.7 4052.4 4053.4 4055.2 4055.3 4096.2 4102.0 4102.1 4102.3 4110.0 4591.3 4722.0 4726.4 4727.9 4729.7 5706.3 5728.1 5729.0 5730.7 5731.8 1440.6 1464.9 1466.1 1466.3 1468.0 983.5 984.8 986.3 990.4 1004.0 5050.8 5051.2 5055.5 5058.2 5071.0 5952.0 5952.1 5952.6 5956.6 5957.5 5041.4 5046.6 5053.4 5054.5 5056.5 3143.1 3188.2 3221.3 3226.2 3228.0 5589.4 5605.3 5607.1 5609.6 5610.7 6092.9 6095.0 6098.9 6104.4 6110.6 5468.7 5592.5 5596.9 5603.7 5606.6 5906.8 5935.6 5937.5 5939.2 5942.5 5575.6 5756.7 5757.1 5762.3 5766.0 5481.5 5550.0 5646.9 5658.5 5659.4 4075.6 4082.3 4102.0 4122.7 4123.9 5790.2 5928.2 5975.1 5993.6 5995.3 5717.9 5775.7 5777.5 5780.6 5784.0 5770.1 5773.0 5775.8 5775.8 5784.8 5479.2 5655.6 5659.6 5660.8 5663.6 5805.5 5808.4 5811.4 5811.5 5819.9 5666.0 5666.4 5669.0 5669.1 5686.5 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,007 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12189.52 Median:495.39 stddev:13873.96 (2800.6%) stddev-sans-first:13885.81 17:41:46 INFO - 2015-12-14 17:41:46,009 DEBUG : BROWSER_OUTPUT: Values: 649.9 715.9 728.3 772.2 800.5 11244.1 11577.9 11581.3 11581.4 11583.6 11533.0 11638.7 11741.4 11751.0 11751.5 803.1 820.2 821.8 821.8 822.0 803.5 810.5 810.7 811.9 812.1 852.7 860.5 861.1 861.2 862.3 843.4 847.8 849.2 850.7 851.8 631.3 631.5 631.7 632.0 632.0 10552.5 10579.6 10668.0 10683.1 10709.5 9696.8 9799.9 9899.2 10091.4 10322.5 8523.1 8610.3 8721.4 8881.1 8920.6 3049.5 3099.0 3116.7 3164.1 3240.9 33987.8 34583.7 38153.6 42063.4 43078.0 8808.0 9077.5 9265.4 9289.3 9941.5 15522.2 15794.7 15795.5 15798.4 15805.4 6400.4 6458.2 6489.5 6550.2 6670.7 3566.2 3645.9 3647.2 3647.5 3705.8 2553.1 2560.6 2594.7 2595.7 2597.2 495.0 495.8 497.0 498.7 500.2 477.7 543.7 561.6 573.1 600.0 8090.6 8660.6 8959.0 9020.8 9183.7 539.5 551.8 605.7 618.8 626.1 3706.8 3944.8 4009.4 4026.8 4031.7 49721.3 50151.7 50267.7 50278.5 50418.0 44044.3 44539.6 44777.0 45053.3 45163.3 34430.1 35102.8 40899.2 46451.3 46657.3 7876.8 8195.2 8219.4 8289.5 8548.3 32213.2 32307.8 32553.5 33056.2 33106.0 37103.8 40925.8 41002.0 41046.6 41544.4 9309.3 9630.9 9928.7 10147.4 10241.7 10618.8 11312.8 11341.9 11368.9 11412.1 10051.9 10542.5 10698.3 10800.4 11227.2 10211.8 10609.5 10860.6 10929.8 11017.3 10155.9 10219.1 10457.9 10724.8 10728.8 8937.7 9157.9 9199.2 9318.0 9321.8 8791.4 9099.7 9103.5 9119.7 9290.4 17:41:46 INFO - 2015-12-14 17:41:46,009 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:41:46 INFO - 2015-12-14 17:41:46,009 DEBUG : BROWSER_OUTPUT: 17:41:46 INFO - 2015-12-14 17:41:46,829 INFO : Browser exited with error code: 0 17:41:46 INFO - 2015-12-14 17:41:46,848 INFO : Completed test dromaeo_css (00:18:36) 17:41:46 INFO - 2015-12-14 17:41:46,848 INFO : Starting test kraken 17:41:46 INFO - 2015-12-14 17:41:46,848 DEBUG : operating with platform_type : linux_ 17:41:46 INFO - 2015-12-14 17:41:46,848 INFO : Initialising browser for kraken test... 17:41:46 INFO - 2015-12-14 17:41:46,854 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9VuXIj/profile http://localhost:42223/getInfo.html 17:41:50 INFO - 2015-12-14 17:41:50,611 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:41:50 INFO - 2015-12-14 17:41:50,611 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:41:50 INFO - 2015-12-14 17:41:50,611 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:41:50 INFO - 2015-12-14 17:41:50,611 DEBUG : BROWSER_OUTPUT: __metrics 17:41:50 INFO - 2015-12-14 17:41:50,714 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:41:50 INFO - 2015-12-14 17:41:50,774 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] 17:41:51 INFO - 2015-12-14 17:41:51,775 INFO : Browser initialized. 17:41:51 INFO - 2015-12-14 17:41:51,776 INFO : Running cycle 1/1 for kraken test... 17:41:51 INFO - 2015-12-14 17:41:51,776 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9VuXIj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:42:03 INFO - 2015-12-14 17:42:03,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 142782464 17:42:03 INFO - 2015-12-14 17:42:03,801 DEBUG : BROWSER_OUTPUT: 17:42:06 INFO - 2015-12-14 17:42:06,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42223/tests/kraken/driver.html?testName=audio-beat-detection) 17:42:06 INFO - 2015-12-14 17:42:06,982 DEBUG : BROWSER_OUTPUT: RSS: Main: 179539968 17:42:06 INFO - 2015-12-14 17:42:06,982 DEBUG : BROWSER_OUTPUT: 17:42:09 INFO - 2015-12-14 17:42:09,699 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42223/tests/kraken/driver.html?testName=audio-dft) 17:42:10 INFO - 2015-12-14 17:42:10,272 DEBUG : BROWSER_OUTPUT: RSS: Main: 168382464 17:42:10 INFO - 2015-12-14 17:42:10,272 DEBUG : BROWSER_OUTPUT: 17:42:13 INFO - 2015-12-14 17:42:13,275 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42223/tests/kraken/driver.html?testName=audio-fft) 17:42:13 INFO - 2015-12-14 17:42:13,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 155377664 17:42:13 INFO - 2015-12-14 17:42:13,844 DEBUG : BROWSER_OUTPUT: 17:42:15 INFO - 2015-12-14 17:42:15,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42223/tests/kraken/driver.html?testName=audio-oscillator) 17:42:16 INFO - 2015-12-14 17:42:16,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 155963392 17:42:16 INFO - 2015-12-14 17:42:16,462 DEBUG : BROWSER_OUTPUT: 17:42:18 INFO - 2015-12-14 17:42:18,715 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:42:19 INFO - 2015-12-14 17:42:19,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 157700096 17:42:19 INFO - 2015-12-14 17:42:19,296 DEBUG : BROWSER_OUTPUT: 17:42:22 INFO - 2015-12-14 17:42:22,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42223/tests/kraken/driver.html?testName=imaging-darkroom) 17:42:22 INFO - 2015-12-14 17:42:22,820 DEBUG : BROWSER_OUTPUT: RSS: Main: 157163520 17:42:22 INFO - 2015-12-14 17:42:22,820 DEBUG : BROWSER_OUTPUT: 17:42:26 INFO - 2015-12-14 17:42:26,206 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42223/tests/kraken/driver.html?testName=imaging-desaturate) 17:42:26 INFO - 2015-12-14 17:42:26,788 DEBUG : BROWSER_OUTPUT: RSS: Main: 158687232 17:42:26 INFO - 2015-12-14 17:42:26,788 DEBUG : BROWSER_OUTPUT: 17:42:29 INFO - 2015-12-14 17:42:29,589 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42223/tests/kraken/driver.html?testName=json-parse-financial) 17:42:30 INFO - 2015-12-14 17:42:30,170 DEBUG : BROWSER_OUTPUT: RSS: Main: 159416320 17:42:30 INFO - 2015-12-14 17:42:30,170 DEBUG : BROWSER_OUTPUT: 17:42:31 INFO - 2015-12-14 17:42:31,943 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:42:32 INFO - 2015-12-14 17:42:32,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 168103936 17:42:32 INFO - 2015-12-14 17:42:32,509 DEBUG : BROWSER_OUTPUT: 17:42:34 INFO - 2015-12-14 17:42:34,370 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:42:34 INFO - 2015-12-14 17:42:34,977 DEBUG : BROWSER_OUTPUT: RSS: Main: 167342080 17:42:34 INFO - 2015-12-14 17:42:34,977 DEBUG : BROWSER_OUTPUT: 17:42:37 INFO - 2015-12-14 17:42:37,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:42:37 INFO - 2015-12-14 17:42:37,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 180744192 17:42:37 INFO - 2015-12-14 17:42:37,682 DEBUG : BROWSER_OUTPUT: 17:42:40 INFO - 2015-12-14 17:42:40,186 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:42:40 INFO - 2015-12-14 17:42:40,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 185217024 17:42:40 INFO - 2015-12-14 17:42:40,772 DEBUG : BROWSER_OUTPUT: 17:42:43 INFO - 2015-12-14 17:42:43,427 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:42:44 INFO - 2015-12-14 17:42:44,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 181477376 17:42:44 INFO - 2015-12-14 17:42:44,009 DEBUG : BROWSER_OUTPUT: 17:42:45 INFO - 2015-12-14 17:42:45,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42223/tests/kraken/driver.html?testName=ai-astar) 17:42:46 INFO - 2015-12-14 17:42:46,041 DEBUG : BROWSER_OUTPUT: RSS: Main: 239943680 17:42:46 INFO - 2015-12-14 17:42:46,041 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,044 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;113;105;111;112;113;103;112;111;112 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;129;157;174;132;153;155;131;168;132 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;160;161;160;162;159;164;162;162;160 17:42:46 INFO - 2015-12-14 17:42:46,045 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;74;87;91;72;71;73;108;89;72 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;133;94;96;95;129;94;95;128 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;110;109;109;109;110;109 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;146;148;146;147;146;147;147 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;96;95;96;95;95;95;95 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;71;72;73;73;72;72;81;70;71 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;67;67;69;68;68;68;68;68;68 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;85;85;84;91;82;84;83;88;83 17:42:46 INFO - 2015-12-14 17:42:46,046 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;121;119;125;118;126;137;117;123;131 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;138;151;152;159;142;153;161;158;144 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;75;70;71;70;71;70;70;70;69;72 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: __start_cc_report 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1019 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: __end_cc_report 17:42:46 INFO - 2015-12-14 17:42:46,047 DEBUG : BROWSER_OUTPUT: __startTimestamp1450143766042__endTimestamp 17:42:46 INFO - 2015-12-14 17:42:46,048 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: Number of tests: 14 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.20 Median:112.00 stddev:3.43 (3.1%) stddev-sans-first:3.63 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: Values: 110.0 113.0 105.0 111.0 112.0 113.0 103.0 112.0 111.0 112.0 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:148.20 Median:154.00 stddev:16.34 (10.6%) stddev-sans-first:17.29 17:42:46 INFO - 2015-12-14 17:42:46,051 DEBUG : BROWSER_OUTPUT: Values: 151.0 129.0 157.0 174.0 132.0 153.0 155.0 131.0 168.0 132.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.20 Median:162.00 stddev:1.48 (0.9%) stddev-sans-first:1.54 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: Values: 162.0 160.0 161.0 160.0 162.0 159.0 164.0 162.0 162.0 160.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.90 Median:73.50 stddev:12.40 (16.9%) stddev-sans-first:12.73 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: Values: 72.0 74.0 87.0 91.0 72.0 71.0 73.0 108.0 89.0 72.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.30 Median:94.50 stddev:17.10 (18.1%) stddev-sans-first:17.73 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 133.0 94.0 96.0 95.0 129.0 94.0 95.0 128.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 146.0 148.0 146.0 147.0 146.0 147.0 147.0 17:42:46 INFO - 2015-12-14 17:42:46,052 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.80 Median:72.50 stddev:3.05 (4.2%) stddev-sans-first:3.23 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: Values: 73.0 71.0 72.0 73.0 73.0 72.0 72.0 81.0 70.0 71.0 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: Values: 67.0 67.0 67.0 69.0 68.0 68.0 68.0 68.0 68.0 68.0 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:2.67 (3.1%) stddev-sans-first:2.83 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: Values: 85.0 85.0 85.0 84.0 91.0 82.0 84.0 83.0 88.0 83.0 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:125.50 stddev:6.20 (4.9%) stddev-sans-first:6.55 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: Values: 126.0 121.0 119.0 125.0 118.0 126.0 137.0 117.0 123.0 131.0 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,053 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.50 Median:152.50 stddev:8.76 (5.7%) stddev-sans-first:8.04 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: Values: 137.0 138.0 151.0 152.0 159.0 142.0 153.0 161.0 158.0 144.0 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:70.50 stddev:1.69 (2.4%) stddev-sans-first:0.87 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: Values: 75.0 70.0 71.0 70.0 71.0 70.0 70.0 70.0 69.0 72.0 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:42:46 INFO - 2015-12-14 17:42:46,054 DEBUG : BROWSER_OUTPUT: 17:42:46 INFO - 2015-12-14 17:42:46,961 INFO : Browser exited with error code: 0 17:42:47 INFO - 2015-12-14 17:42:47,013 INFO : Completed test kraken (00:01:00) 17:42:47 INFO - 2015-12-14 17:42:47,013 INFO : Starting test v8_7 17:42:47 INFO - 2015-12-14 17:42:47,013 DEBUG : operating with platform_type : linux_ 17:42:47 INFO - 2015-12-14 17:42:47,013 INFO : Initialising browser for v8_7 test... 17:42:47 INFO - 2015-12-14 17:42:47,019 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTGmZKg/profile http://localhost:42223/getInfo.html 17:42:50 INFO - 2015-12-14 17:42:50,899 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 17:42:50 INFO - 2015-12-14 17:42:50,899 DEBUG : BROWSER_OUTPUT: colorDepth:24 17:42:50 INFO - 2015-12-14 17:42:50,899 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 17:42:50 INFO - 2015-12-14 17:42:50,899 DEBUG : BROWSER_OUTPUT: __metrics 17:42:50 INFO - 2015-12-14 17:42:50,996 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:42:51 INFO - 2015-12-14 17:42:51,063 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:42:51 INFO - 2015-12-14 17:42:51,890 INFO : Browser initialized. 17:42:51 INFO - 2015-12-14 17:42:51,890 INFO : Running cycle 1/1 for v8_7 test... 17:42:51 INFO - 2015-12-14 17:42:51,891 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTGmZKg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 17:43:03 INFO - 2015-12-14 17:43:03,806 DEBUG : BROWSER_OUTPUT: RSS: Main: 142561280 17:43:03 INFO - 2015-12-14 17:43:03,807 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,236 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42223/tests/v8_7/run.html (next: http://localhost:42223/tests/v8_7/run.html) 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 442540032 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: __start_tp_report 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 17:43:25 INFO - 2015-12-14 17:43:25,487 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 17:43:25 INFO - 2015-12-14 17:43:25,488 DEBUG : BROWSER_OUTPUT: |0;Richards;165.48072149594572 17:43:25 INFO - 2015-12-14 17:43:25,488 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;186.11576400521125 17:43:25 INFO - 2015-12-14 17:43:25,488 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.623542476402 17:43:25 INFO - 2015-12-14 17:43:25,488 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5218.75 17:43:25 INFO - 2015-12-14 17:43:25,488 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1968.5039370078741 17:43:25 INFO - 2015-12-14 17:43:25,489 DEBUG : BROWSER_OUTPUT: |5;Earley;791.7656373713381 17:43:25 INFO - 2015-12-14 17:43:25,489 DEBUG : BROWSER_OUTPUT: |6;Boyer;12195.121951219513 17:43:25 INFO - 2015-12-14 17:43:25,489 DEBUG : BROWSER_OUTPUT: |7;RegExp;26710.526315789473 17:43:25 INFO - 2015-12-14 17:43:25,489 DEBUG : BROWSER_OUTPUT: |8;Splay;609.7560975609756 17:43:25 INFO - 2015-12-14 17:43:25,489 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: __end_tp_report 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: __startTimestamp1450143805486__endTimestamp 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: Number of tests: 10 17:43:25 INFO - 2015-12-14 17:43:25,490 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.48 Median:165.48 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: Values: 165.5 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:186.12 Median:186.12 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: Values: 186.1 17:43:25 INFO - 2015-12-14 17:43:25,491 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,492 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.62 Median:277.62 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,492 DEBUG : BROWSER_OUTPUT: Values: 277.6 17:43:25 INFO - 2015-12-14 17:43:25,492 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,492 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5218.75 Median:5218.75 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,492 DEBUG : BROWSER_OUTPUT: Values: 5218.8 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1968.50 Median:1968.50 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: Values: 1968.5 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:791.77 Median:791.77 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: Values: 791.8 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12195.12 Median:12195.12 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,493 DEBUG : BROWSER_OUTPUT: Values: 12195.1 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26710.53 Median:26710.53 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: Values: 26710.5 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:609.76 Median:609.76 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: Values: 609.8 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: Values: 4835.7 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 17:43:25 INFO - 2015-12-14 17:43:25,494 DEBUG : BROWSER_OUTPUT: 17:43:26 INFO - 2015-12-14 17:43:26,413 INFO : Browser exited with error code: 0 17:43:26 INFO - 2015-12-14 17:43:26,432 INFO : Completed test v8_7 (00:00:39) 17:43:26 INFO - 2015-12-14 17:43:26,499 INFO : Completed test suite (00:20:16) 17:43:26 INFO - 2015-12-14 17:43:26,499 DEBUG : Working with test: dromaeo_css 17:43:26 INFO - 2015-12-14 17:43:26,500 DEBUG : Generating results file: dromaeo_css 17:43:26 INFO - 2015-12-14 17:43:26,501 DEBUG : Working with test: kraken 17:43:26 INFO - 2015-12-14 17:43:26,501 DEBUG : Generating results file: kraken 17:43:26 INFO - 2015-12-14 17:43:26,501 INFO : javascript benchmark 17:43:26 INFO - 2015-12-14 17:43:26,501 DEBUG : Working with test: v8_7 17:43:26 INFO - 2015-12-14 17:43:26,501 DEBUG : Generating results file: v8_7 17:43:26 INFO - 2015-12-14 17:43:26,502 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:43:26 INFO - 2015-12-14 17:43:26,568 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 17:43:26 INFO - 2015-12-14 17:43:26,568 DEBUG : process_Request line: dromaeo_css 6243.29 graph.html#tests=[[72,94,35]] 17:43:26 INFO - 2015-12-14 17:43:26,568 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:43:26 INFO - 2015-12-14 17:43:26,603 DEBUG : process_Request line: kraken 1527.20 graph.html#tests=[[232,94,35]] 17:43:26 INFO - 2015-12-14 17:43:26,603 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:43:26 INFO - 2015-12-14 17:43:26,635 DEBUG : process_Request line: v8_7 19813.21 graph.html#tests=[[230,94,35]] 17:43:26 INFO - 2015-12-14 17:43:26,636 INFO : javascript benchmark 17:43:26 INFO - 2015-12-14 17:43:26,640 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2635.1040646180295, 2687.9731202687967, 2690.1794952539467, 2697.757201851833, 2716.891324347026, 4566.223741963866, 4694.85915422537, 4761.40482439695, 4785.258284965831, 4789.23372260438, 4628.444586649607, 4661.953380466194, 4712.3638308828295, 4715.646326525507, 4793.281007848825, 4342.109867477176, 4364.869053928387, 4389.758563279019, 4503.347014682885, 4508.77456127194, 4428.667849911253, 4474.910501789962, 4497.505274419811, 4498.595126438619, 4533.659975501851, 4219.050713589449, 4261.659067274611, 4313.137372525507, 4313.633341166004, 4373.562643735633, 4379.956200437987, 4393.714408563433, 4394.384786129945, 4402.691811573191, 4446.155230506193, 4363.1273745250755, 4382.364557139229, 4389.561043895616, 4411.382406463098, 4417.668674849367, 4526.818927242906, 4590.495045544987, 4642.14120387726, 4644.674872758908, 4722.83470078549, 5107.182850743863, 5123.180291153435, 5126.256692779526, 5127.0274722806025, 5130.897382052375, 5129.358830146231, 5151.072806894757, 5153.9742301288625, 5156.948430515685, 5196.714180720059, 4616.6537509686905, 4692.272697731857, 4753.714777113384, 4754.738489383117, 4816.662833601685, 5220.895582088337, 5258.053550360972, 5262.868428289324, 5266.0257852297445, 5312.840614781563, 5032.043911656773, 5048.798048078109, 5054.949450505448, 5057.747112644353, 5096.235564665256, 4789.042191561702, 4916.262560615936, 4921.8523444296725, 4936.062148191832, 5031.77357018935, 5034.144195486776, 5046.9747651261505, 5051.090803655304, 5052.9242061369105, 5104.668196567211, 4996.6752161110135, 5003.224500202475, 5010.724410157476, 5024.648274620741, 5026.447090820006, 4023.3562629979065, 4106.856260030884, 4109.4246805447265, 4149.481314835645, 4245.830166793356, 3701.444783282529, 3719.1445967427644, 3719.6094410087094, 3757.755745876509, 3758.699304055669, 4356.978215108904, 4400.141972315432, 4405.140997505456, 4431.578999994996, 4442.0, 4764.023375208074, 4874.2201247800185, 4877.170880950176, 4935.235038569028, 5079.517445842639, 4400.119976004812, 4405.625521830616, 4460.955390446119, 4469.173202957463, 4478.350639157304, 4500.572445617661, 4518.051209246095, 4537.954620453753, 4538.251188553918, 4574.588287054181, 4592.47186573535, 4701.647376446712, 4703.929441058455, 4868.95131048692, 4870.26945958102, 4964.304997300309, 4978.850634480971, 5037.471065538066, 5038.496150384932, 5083.872903177302, 4807.879803004953, 4821.686590371614, 4824.734639594856, 4827.155247831685, 4863.683860549053, 4763.880902977453, 4866.48901865299, 4871.171900776947, 5020.748962551785, 5060.139776237975, 4930.087933732271, 4999.0501804657, 4999.525045120708, 4999.875003124951, 5055.797768089383, 4841.322214889989, 4960.77676504551, 4972.7762250699525, 4973.502649734997, 5015.423226328873, 4727.929081063856, 4836.685615434985, 4839.516048395132, 4945.8021679132435, 5023.422306434807, 4768.809247630056, 4854.80580776765, 4872.731999739906, 4895.216765317532, 4920.803167873388, 4977.552020318189, 4983.352164218773, 4990.72551009698, 4996.900061998813, 5037.471065538066, 4641.74470404131, 4875.585575226146, 4884.194107972145, 4885.1450996076255, 4887.90224195507], "unit": "ms", "name": "dojo.html", "value": 4643.528956381962}, {"lowerIsBetter": false, "replicates": [565.1615453018477, 571.0321005895009, 580.7909152705026, 594.6372712645287, 596.8776400837826, 27731.0, 35557.0, 36442.8177859111, 36474.0, 36477.0, 40743.59256407435, 41190.794046029805, 41292.0, 41292.17415651685, 41304.79347603258, 7020.684069216872, 7148.0, 7148.678309476086, 7721.652525636346, 7873.370130389555, 10263.640772572962, 10492.527836247387, 10514.894851051506, 10516.053555180033, 10532.47337633119, 10154.086132248096, 10242.487875606228, 10272.691819245434, 10273.691789246297, 10277.794444111114, 7136.607486588262, 7338.926610733931, 7342.669579868865, 7369.4841361104745, 9977.401355918668, 7081.645917704094, 9914.9504252479, 10111.191104711605, 10132.645357412455, 10136.391816491034, 34306.71166104237, 34515.482267766005, 34681.65318346835, 34710.0, 34757.13107172315, 3020.6677265500775, 3038.9439074828183, 3084.9973758528345, 3104.4567200740007, 3555.5911070227, 28342.716572834215, 28371.57442638362, 28615.856920715472, 28697.139085827457, 28942.0, 3334.6498617645284, 3425.5718035245595, 3437.3984552703178, 3477.5131481592343, 3565.5899571549085, 207221.0, 209319.0, 209387.0, 210397.9480102605, 210597.0, 51485.7425712869, 53364.733176334645, 57008.42991570114, 62703.37296627067, 64306.0, 55685.164722529196, 55986.72006640022, 56056.439435605935, 56284.0, 56396.0, 12855.29295888735, 13046.282454465096, 13046.804297935538, 13066.608001759962, 13068.085234033526, 9750.95124524387, 9826.459544724965, 9840.606375745034, 9850.310478266596, 9985.151262142654, 19402.902985484983, 19542.413727588195, 19542.609147816966, 19698.606027879363, 19783.40649780509, 7607.0, 7811.101723301779, 7900.430196711305, 7913.683452661944, 7928.4450088494405, 824.7484517222305, 874.6632546469655, 889.915458031486, 918.4535201555065, 989.8861630912393, 26815.86592067066, 27230.72769272282, 29756.10731678053, 31173.688263117532, 31444.0, 944.593824655391, 954.8997355277735, 965.560669895196, 1024.3137098144261, 1067.6263307842194, 13808.723825523433, 13887.305634718223, 13930.16419014842, 14060.648483787783, 14068.507602233873, 305751.0, 310505.0, 310543.0, 310822.0, 311929.0, 47166.76416617895, 47663.28505072427, 47698.5230147701, 47714.0, 47764.5223547767, 1203.8435003449495, 1217.3000524698334, 1219.804831227017, 1221.3343727668441, 1242.0808601634906, 23564.646530301718, 29359.853200733858, 30940.226494336923, 31193.0, 31276.218094547818, 43735.34396984112, 44414.77792611016, 44428.0, 44472.11055778931, 44478.0, 238787.0, 241863.0, 241870.0, 241874.0, 242512.0, 34713.479297810045, 41922.58077419187, 42304.0, 42593.36109958291, 43366.566334336254, 38654.80672596731, 38822.223555529294, 38885.6111438882, 38921.221575568896, 39376.606233937295, 32395.0, 32837.17907052343, 33302.0, 33712.49431258484, 33777.83111084429, 33091.66908330886, 33628.0, 33724.831375842965, 33788.662113379534, 33944.490832638025, 33642.299347072294, 33870.66129338675, 38820.6117938817, 39598.80200599094, 39615.80192099021, 28770.280742980754, 30288.394232115676, 30430.695693042788, 30664.693353066184, 30851.845740771154, 23824.642630360213, 24176.274711758677, 25157.0, 25285.241442756746, 25545.61681574815], "unit": "ms", "name": "ext.html", "value": 17617.447763806264}, {"lowerIsBetter": false, "replicates": [331.8622771549807, 332.35025525098433, 333.19034745568257, 334.49324273725296, 336.40792205717946, 12676.746465070693, 13098.969659702761, 15176.696466070673, 15561.144137072471, 16055.83944160558, 9385.436873787561, 9671.081247281514, 9699.175570076552, 13713.794293085612, 14086.788698169536, 4433.246348120819, 4628.953710462886, 4630.606398456135, 4630.791614377345, 4632.884177895563, 3547.0, 4879.536444037829, 5282.31329927109, 5332.92000619991, 5334.199870019509, 5241.161414173733, 5279.524842764151, 5288.576913846883, 5293.735313234342, 5297.020051290504, 5186.3257776489, 5335.866603334909, 5336.17289320154, 5336.546393556552, 5337.306150200449, 5124.641275110744, 5138.049460849755, 5138.486151384869, 5141.845744627667, 5215.0, 11577.594784182513, 11922.880771192264, 11997.740237275048, 11998.340091294975, 12026.037916966621, 1677.2955358749232, 1712.700277451441, 1748.9825101748947, 1763.9118044097743, 1787.7932395632895, 8806.559672016374, 8820.073892241286, 8912.509811960339, 8916.331275154389, 9135.406198597137, 808.9838203235902, 1782.3976993589245, 1787.9553011174694, 1807.656545256397, 1847.7782666080022, 54523.45476545223, 56581.43418565844, 57153.85692286173, 57875.0, 59132.11301830476, 10002.849957250646, 10047.447390393452, 10048.648297309559, 10076.74808129788, 10101.393916365041, 12505.874941250471, 12517.874821251853, 12523.624291271277, 12532.937335313265, 12542.0, 6632.801015969432, 6675.132232809704, 6679.799606011828, 6727.159105111861, 6732.461403087741, 6225.252969643581, 6410.391012853772, 6434.806955791334, 6444.162258906312, 6447.25856526496, 4198.202341555095, 4202.768847713405, 4216.51510076339, 4278.4010238566625, 4316.201502722007, 1577.1798664694295, 1620.8136064352514, 1654.8924319919167, 1669.6159883226926, 1687.4684474390529, 371.77321833681435, 373.79254513744587, 389.06235971309576, 831.6423937706844, 859.879616853641, 8494.405391622528, 9443.811123777487, 9851.605935762489, 10106.191504679608, 10179.134773544183, 397.7891717389788, 400.709485622921, 403.7012610668143, 404.82997141200764, 407.44790808454115, 5089.211172268304, 5680.857978550486, 5721.5708821838525, 5741.45456181662, 5776.740046697909, 512251.0, 514461.0, 514548.0, 529123.0, 532916.0, 74792.25207747961, 75318.62340688262, 75788.24211757812, 75837.0, 76069.61965190139, 436.75541696649975, 438.6425063573241, 441.111161010564, 442.48229571400896, 442.48450555103585, 9618.951905240709, 9820.116189542976, 9820.607175712892, 9823.950880245553, 9920.851187232052, 33648.663513364554, 33859.66140338565, 34679.30641387209, 35313.47029794606, 35558.0, 45413.00456949799, 46868.531314686414, 48626.27060594023, 49016.0, 49445.50554494409, 8500.53247071417, 8817.603207855791, 8941.37410381293, 8957.372983891066, 8968.820623587622, 10354.378737275787, 10377.16982641402, 10388.896111038794, 15935.21133801557, 17113.828861711223, 8428.410011299151, 8753.0, 8765.430247033923, 8932.955335223282, 9527.142557169887, 7699.345555627834, 8996.685116020908, 9291.907080929104, 9353.85969210476, 9556.85664715016, 9551.761205969906, 9740.172085372822, 9831.606735730491, 10099.646512372326, 10101.191904647463, 14068.859311406755, 14091.929540352232, 14318.212498312692, 14332.355044022836, 14644.926775366055, 15084.39662413535, 15253.08481491114, 15337.156456395005, 15782.447614333443, 15812.209389530248], "unit": "ms", "name": "jquery.html", "value": 7210.6585184761225}, {"lowerIsBetter": false, "replicates": [1234.3704710597592, 1648.9752653710195, 1696.1264948551498, 1715.6054107555262, 1735.4446577095327, 6247.968760156198, 7128.0, 7235.782926512213, 7298.233685463017, 7325.157606875217, 7220.458465615086, 7361.263873612636, 7408.777736667895, 7414.888776668341, 7420.962895185517, 1748.9038102904335, 1788.0255260882784, 1790.7045337519294, 2100.747910250768, 2137.6152292587326, 1779.4483710049926, 1793.417139429684, 2009.2264478175862, 2086.8121869031784, 2089.665653495441, 2086.5722526882028, 2091.623507768601, 2093.80108889656, 2095.706601075843, 2096.727425434691, 2046.1610739596767, 2094.7695753467106, 2096.0462989339812, 2096.06725007371, 2097.9895100524554, 2048.283100914683, 2073.5956488484753, 2075.0351086744586, 2076.4289820299387, 2103.1797598936428, 7190.9280907191305, 7289.817754556178, 7329.743458978964, 7332.8900066499045, 7334.743283985035, 4133.111381053058, 4174.457320548309, 4192.706510544263, 4196.181744559812, 4211.263028969948, 2625.566781481053, 2642.6036094585775, 2681.691605465377, 2697.2447714639734, 2707.7021527631946, 854.7521218846528, 857.9742607721778, 894.5884892949186, 897.2194191053825, 913.0732306708696, 9855.211583073336, 9901.306908516406, 9925.0, 9946.254030947708, 9952.552135153936, 3922.5096862892137, 3976.5824588418377, 4028.879133625996, 4033.556308806029, 4038.273110840018, 8541.871871921927, 8546.444481108709, 8549.27331176857, 8562.100979397199, 8568.143185681507, 4213.599708027732, 4216.704830661824, 4219.261629214937, 4219.438807641192, 4220.2192594370135, 3730.0488375464083, 3757.887263382103, 3758.1356288053903, 3763.040424691741, 3768.171002379472, 3433.6738009889023, 3447.3105378924315, 3453.3611281912927, 3458.75788694794, 3478.860845566149, 963.5760265483165, 986.0287616697548, 1087.6410784441116, 1102.4928532874967, 1106.3085571517802, 811.5009269299338, 811.6469335838929, 812.6058861452191, 852.6078004118051, 930.9441433514011, 6796.422304104108, 6871.347222013901, 6882.759103431456, 6894.207166175854, 6894.655267236618, 1022.3201570955232, 1036.6838114375037, 1040.453761775074, 1049.9370037797758, 1060.7083052160563, 5274.050670879278, 5328.254044433782, 5332.146856503018, 5337.932413517235, 5356.169793681907, 8102.918970810217, 8305.626246818907, 8308.29379502737, 8327.417080804285, 8533.146685331418, 8450.281726053354, 8579.356548258906, 8604.569771511524, 8630.0, 8646.78383040429, 6859.862802744017, 6935.757248496379, 6959.478039147084, 7018.368346848808, 7071.257517960643, 6700.162479690039, 6758.391744742997, 6760.560563563353, 6764.053032575542, 6770.458363330823, 7799.805004874924, 7891.684332626631, 8037.31682806968, 8053.0336359634875, 8093.352531797324, 7980.281774640311, 8077.919220807952, 8109.067457242257, 8212.343012558862, 8240.917590824016, 6365.395287447685, 6365.440467085408, 6441.259255185713, 6446.903296450463, 6448.161738973903, 5831.620944638585, 6721.731130754715, 6843.315668433117, 6843.315668433117, 6862.588244705334, 5870.0607902735355, 6170.444659980623, 6318.494520438446, 6395.808125756234, 6532.314107018695, 6472.676366181766, 6485.254195767548, 6491.902621460587, 6573.375529324707, 6576.408123268929, 6363.7772677956045, 6396.072569477493, 6434.967825160844, 6435.871282574416, 6462.386073323027, 6071.2714474263375, 6086.7260973255425, 6091.086337049478, 6100.664463454553, 6112.633242005494, 6094.634321940698, 6120.846978825387, 6123.050927106306, 6128.417800308964, 6134.263888333428], "unit": "ms", "name": "mootools.html", "value": 4100.191632500274}, {"lowerIsBetter": false, "replicates": [301.7736697476892, 308.84249032993165, 310.22753344173003, 316.05657113516133, 318.8310195596335, 5314.92027619585, 5362.8123015694455, 5396.541293990011, 5456.181572764087, 5530.5299049580735, 5228.79084836606, 5391.892162156764, 5393.083175860103, 5398.838034858951, 5403.2705584746145, 3952.664023558001, 4197.097624010837, 4198.265303571863, 4203.138356636898, 4206.852760153403, 4038.5759495253014, 4055.5538890721996, 4170.893698682161, 4172.853950111747, 4176.436181115541, 4104.035551645361, 4113.773742444165, 4114.567970363114, 4123.505179378479, 4128.772917489537, 4113.259613269625, 4115.814788334532, 4123.092919557693, 4126.773027483488, 4151.709380343377, 3599.8200089995394, 3599.8200089995394, 3600.47793070006, 3606.7114630829733, 3609.2781443711365, 5128.589712822965, 5224.92162617561, 5238.345206849144, 5283.022640811462, 5308.309919710451, 4788.9521104788855, 4789.257665061921, 4791.616670666338, 4793.161196790547, 4795.2807078938085, 5473.0969390050595, 5746.827595172152, 5758.510526605243, 5761.279840019997, 5764.7117644117625, 3840.1935593525395, 3861.1119442528093, 3889.358255887775, 3905.2384784966944, 3911.100446897201, 5923.052311630162, 5996.19051428056, 6006.609570377956, 6017.308009578867, 6023.367546407651, 4049.7367671101283, 4052.4326594276827, 4053.3717273822604, 4055.2497787909333, 4055.3105971984833, 4096.242195193899, 4101.97949010259, 4102.077032667626, 4102.323116685773, 4109.97945010273, 4591.265397536445, 4722.008378240539, 4726.409198850144, 4727.858164255078, 4729.668923175344, 5706.3152421709665, 5728.083506638918, 5729.0, 5730.684812335278, 5731.77072917087, 1440.6470414748453, 1464.868161865416, 1466.0836976889443, 1466.3108339080616, 1468.0, 983.5180761426992, 984.8165853416774, 986.2603047714215, 990.3958585262984, 1004.0311099788739, 5050.772715227824, 5051.166557518042, 5055.469175736568, 5058.190689489664, 5070.974645126751, 5952.0, 5952.136940143655, 5952.553558483044, 5956.583039187215, 5957.493613042853, 5041.395032596112, 5046.62150338726, 5053.444121146671, 5054.519820617109, 5056.494350564988, 3143.0570828751283, 3188.2029492626843, 3221.291315910496, 3226.209578653245, 3227.9677203227666, 5589.413111623302, 5605.299337582802, 5607.130894711327, 5609.6353737007785, 5610.663360198401, 6092.908606370908, 6094.988653744343, 6098.908516372258, 6104.389561043949, 6110.633361998294, 5468.699221542854, 5592.496675299161, 5596.916046259309, 5603.663780173202, 5606.579506537026, 5906.822795316148, 5935.643861368331, 5937.495312898452, 5939.227900372924, 5942.494887934401, 5575.578227551949, 5756.712164391847, 5757.0788673811985, 5762.308522977102, 5766.048601980626, 5481.506664400223, 5550.028744969695, 5646.887062258814, 5658.5473162147755, 5659.434056594308, 4075.633193012643, 4082.3058430750907, 4101.979490102531, 4122.732022418533, 4123.948393159725, 5790.218320526844, 5928.199693041384, 5975.073863551156, 5993.640381577119, 5995.340512543704, 5717.8570535736935, 5775.711214439345, 5777.480026797609, 5780.5953583248765, 5783.987802134694, 5770.076787713946, 5772.9711351442975, 5775.768969241183, 5775.826725198251, 5784.768609255582, 5479.150731636602, 5655.575831812713, 5659.603827732019, 5660.801871934463, 5663.57523185771, 5805.506531944832, 5808.39011903744, 5811.4188581143235, 5811.476967072984, 5819.94180058194, 5665.971670141787, 5666.405027472221, 5668.971655141698, 5669.092945128759, 5686.459786320293], "unit": "ms", "name": "prototype.html", "value": 4280.533389794834}, {"lowerIsBetter": false, "replicates": [649.9285078641349, 715.8711431942252, 728.3481284250596, 772.1930582541244, 800.4996876951905, 11244.100471962243, 11577.884221157796, 11581.305121692694, 11581.36302503362, 11583.594574189905, 11533.0, 11638.709032274177, 11741.354225517593, 11751.001164900994, 11751.471183796728, 803.0764620686214, 820.1716266570747, 821.7534739578133, 821.7822277096574, 822.042320696388, 803.5138741061661, 810.5136917849272, 810.712197170005, 811.8903947967037, 812.1229072601576, 852.6802449081595, 860.513809697522, 861.1216559109704, 861.2205953611959, 862.2756884217256, 843.3969711656165, 847.7965288330818, 849.2314455417826, 850.7107583421666, 851.7742798158492, 631.343402861024, 631.5105793010408, 631.671530803984, 631.974721011159, 632.0045927663948, 10552.47237638115, 10579.629712960086, 10668.039876411083, 10683.09193718527, 10709.464526773709, 9696.806063878683, 9799.853002204973, 9899.208063354914, 10091.444970526618, 10322.48387580618, 8523.062463129112, 8610.311175105977, 8721.389502734812, 8881.06748791374, 8920.598573064228, 3049.451098802215, 3099.02380750063, 3116.688331166888, 3164.0507847645845, 3240.91897702557, 33987.83006084978, 34583.6541634583, 38153.618463815284, 42063.36904946428, 43078.0, 8808.0, 9077.500737459372, 9265.44407335562, 9289.349745517884, 9941.502924853728, 15522.22388880574, 15794.68410631804, 15795.526134215992, 15798.36806527726, 15805.36778528869, 6400.359964003563, 6458.225012998376, 6489.480841532761, 6550.181227346582, 6670.699818508082, 3566.233259849145, 3645.9453108203397, 3647.2471636388864, 3647.4893514907935, 3705.8332375043187, 2553.1319351420607, 2560.5775047117017, 2594.714581396045, 2595.7014943281765, 2597.1689059500777, 494.9951598255547, 495.77690039481655, 497.0258293744229, 498.68831980012493, 500.1622282676502, 477.6656340561621, 543.7471575717232, 561.6405500479697, 573.0973716396692, 600.0129786501182, 8090.595470226465, 8660.566971651519, 8958.955205223932, 9020.81958360829, 9183.724488265363, 539.54947618738, 551.7572268202058, 605.7062324772481, 618.8174488525896, 626.0671599316985, 3706.7961262130843, 3944.8422063117155, 4009.3985902114914, 4026.838926442968, 4031.7177797554477, 49721.25418118731, 50151.74924125356, 50267.74866125646, 50278.49721502811, 50418.0, 44044.33933491, 44539.554604453544, 44777.0, 45053.32420013702, 45163.32255016177, 34430.13924651904, 35102.824485877405, 40899.182016359504, 46451.30323045157, 46657.30014049792, 7876.763697089096, 8195.180481951757, 8219.383546233938, 8289.502629842229, 8548.316134709332, 32213.194670133435, 32307.838460807547, 32553.511697324084, 33056.1735956603, 33106.0, 37103.81448092742, 40925.795371022956, 41002.0, 41046.58953410428, 41544.37683434811, 9309.255259579082, 9630.855537166808, 9928.652497162564, 10147.391156530632, 10241.74395640115, 10618.84071738909, 11312.773744524899, 11341.943290283825, 11368.886311136783, 11412.087033037504, 10051.899481005097, 10542.472876355998, 10698.304610200312, 10800.405977671304, 11227.21409501327, 10211.783288264172, 10609.522571484147, 10860.619878304222, 10929.781404372026, 11017.338959662446, 10155.89844101579, 10219.080282774587, 10457.895421045692, 10724.839127413252, 10728.785424291627, 8937.687180948895, 9157.862632060658, 9199.218066464426, 9318.0, 9321.766955826159, 8791.428557143765, 9099.727008189764, 9103.544822758704, 9119.726408207764, 9290.396124251902], "unit": "ms", "name": "yui.html", "value": 5719.66042745932}], "name": "dromaeo_css", "value": 6243.2939586270495}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 113.0, 105.0, 111.0, 112.0, 113.0, 103.0, 112.0, 111.0, 112.0], "unit": "ms", "name": "ai-astar", "value": 110.2}, {"lowerIsBetter": true, "replicates": [151.0, 129.0, 157.0, 174.0, 132.0, 153.0, 155.0, 131.0, 168.0, 132.0], "unit": "ms", "name": "audio-beat-detection", "value": 148.2}, {"lowerIsBetter": true, "replicates": [162.0, 160.0, 161.0, 160.0, 162.0, 159.0, 164.0, 162.0, 162.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 87.0, 91.0, 72.0, 71.0, 73.0, 108.0, 89.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 80.9}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 133.0, 94.0, 96.0, 95.0, 129.0, 94.0, 95.0, 128.0], "unit": "ms", "name": "audio-oscillator", "value": 105.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 146.0, 148.0, 146.0, 147.0, 146.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 72.0, 73.0, 73.0, 72.0, 72.0, 81.0, 70.0, 71.0], "unit": "ms", "name": "json-parse-financial", "value": 72.8}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 85.0, 84.0, 91.0, 82.0, 84.0, 83.0, 88.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [126.0, 121.0, 119.0, 125.0, 118.0, 126.0, 137.0, 117.0, 123.0, 131.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [137.0, 138.0, 151.0, 152.0, 159.0, 142.0, 153.0, 161.0, 158.0, 144.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 149.5}, {"lowerIsBetter": true, "replicates": [75.0, 70.0, 71.0, 70.0, 71.0, 70.0, 70.0, 70.0, 69.0, 72.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 70.8}], "name": "kraken", "value": 1527.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.48072149594572], "unit": "score", "name": "Richards", "value": 213.32998600000002}, {"lowerIsBetter": false, "replicates": [186.11576400521125], "unit": "score", "name": "DeltaBlue", "value": 355.2520139999999}, {"lowerIsBetter": false, "replicates": [277.623542476402], "unit": "score", "name": "Encrypt", "value": 958.7839620000002}, {"lowerIsBetter": false, "replicates": [5218.75], "unit": "score", "name": "Decrypt", "value": 51.00474251497002}, {"lowerIsBetter": false, "replicates": [1968.5039370078741], "unit": "score", "name": "RayTrace", "value": 375.9144119999999}, {"lowerIsBetter": false, "replicates": [791.7656373713381], "unit": "score", "name": "Earley", "value": 841.7427690000002}, {"lowerIsBetter": false, "replicates": [12195.121951219513], "unit": "score", "name": "Boyer", "value": 54.64996600000001}, {"lowerIsBetter": false, "replicates": [26710.526315789473], "unit": "score", "name": "RegExp", "value": 34.105842364532016}, {"lowerIsBetter": false, "replicates": [609.7560975609756], "unit": "score", "name": "Splay", "value": 133.64524}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 19813.210489663394}]} 17:43:26 INFO - RETURN: dromaeo_css: 6243.29 17:43:26 INFO - RETURN: kraken: 1527.20 17:43:26 INFO - RETURN: v8_7: 19813.21 17:43:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19813.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1527.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6243.29"}}} 17:43:26 INFO - Return code: 0 17:43:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:43:26 INFO - # TBPL SUCCESS # 17:43:26 INFO - Running post-action listener: _resource_record_post_action 17:43:26 INFO - Running post-run listener: _resource_record_post_run 17:43:27 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 524779520; Read time: 4; Write time: 626492 17:43:27 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 232427520; Read time: 0; Write time: 411488 17:43:27 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 4096; Write bytes: 277258240; Read time: 4; Write time: 188560 17:43:27 INFO - Running post-run listener: _upload_blobber_files 17:43:27 INFO - Blob upload gear active. 17:43:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 17:43:27 INFO - Copying logs to upload dir... 17:43:27 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1264.929807 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-12-14 17:43:27.857825) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-14 17:43:27.861802) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009966 build_url: 'https://queue.taskcluster.net/v1/task/o0jHRqzCQV25rtdxHDakhA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-14 17:43:27.917592) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:43:27.917949) ========= 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=6d5287d839824eebc594b0c9000001d1-1450142541.350948-66881260 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-14 17:43:27.967214) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 17:43:27.967675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-14 17:43:27.967990) ========= ========= Total master_lag: 0.23 =========