builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1462987232.28 results: success (0) buildid: 20160511073009 builduid: b71ac218f8c14983badea0d4759aed2e revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.280388) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.280870) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.281179) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.289845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.290129) ========= 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=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 10:20:32-- 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% 5.65M=0.002s 2016-05-11 10:20:32 (5.65 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.334679 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.632932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.633282) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009683 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.680755) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:32.681202) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --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 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 --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=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 10:20:32,838 truncating revision to first 12 chars 2016-05-11 10:20:32,838 Setting DEBUG logging. 2016-05-11 10:20:32,838 attempt 1/10 2016-05-11 10:20:32,838 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3461f3cae784?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 10:20:33,407 unpacking tar archive at: mozilla-central-3461f3cae784/testing/mozharness/ program finished with exit code 0 elapsedTime=0.811778 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:33.504982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:33.505678) ========= script_repo_revision: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:33.506464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:33.507059) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 10:20:33.516413) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-05-11 10:20:33.517062) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:20:33 INFO - MultiFileLogger online at 20160511 10:20:33 in /builds/slave/test 10:20:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:20:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:33 INFO - {'append_to_log': False, 10:20:33 INFO - 'base_work_dir': '/builds/slave/test', 10:20:33 INFO - 'blob_upload_branch': 'Firefox', 10:20:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:33 INFO - 'branch': 'Firefox', 10:20:33 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:33 INFO - 'config_files': ('talos/linux_config.py',), 10:20:33 INFO - 'default_actions': ('clobber', 10:20:33 INFO - 'read-buildbot-config', 10:20:33 INFO - 'download-and-extract', 10:20:33 INFO - 'populate-webroot', 10:20:33 INFO - 'create-virtualenv', 10:20:33 INFO - 'install', 10:20:33 INFO - 'run-tests'), 10:20:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:33 INFO - 'download_minidump_stackwalk': True, 10:20:33 INFO - 'download_symbols': 'ondemand', 10:20:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:33 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:33 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:33 INFO - 'installer_path': 'installer.exe', 10:20:33 INFO - 'log_level': 'info', 10:20:33 INFO - 'log_name': 'talos', 10:20:33 INFO - 'log_to_console': True, 10:20:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:20:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:20:33 INFO - 'opt_config_files': (), 10:20:33 INFO - 'pip_index': False, 10:20:33 INFO - 'sps_profile': False, 10:20:33 INFO - 'sps_profile_interval': 0, 10:20:33 INFO - 'suite': 'dromaeojs', 10:20:33 INFO - 'system_bits': '32', 10:20:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:33 INFO - 'title': 'talos-linux64-ix-017', 10:20:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:33 INFO - 'use_talos_json': True, 10:20:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:33 INFO - 'work_dir': 'build'} 10:20:33 INFO - ##### 10:20:33 INFO - ##### Running clobber step. 10:20:33 INFO - ##### 10:20:33 INFO - Running pre-action listener: _resource_record_pre_action 10:20:33 INFO - Running main action method: clobber 10:20:33 INFO - rmtree: /builds/slave/test/build 10:20:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:34 INFO - Running post-action listener: _resource_record_post_action 10:20:34 INFO - ##### 10:20:34 INFO - ##### Finished clobber step (success) 10:20:34 INFO - ##### 10:20:34 INFO - ##### 10:20:34 INFO - ##### Running read-buildbot-config step. 10:20:34 INFO - ##### 10:20:34 INFO - Running pre-action listener: _resource_record_pre_action 10:20:34 INFO - Running main action method: read_buildbot_config 10:20:34 INFO - Using buildbot properties: 10:20:34 INFO - { 10:20:34 INFO - "project": "", 10:20:34 INFO - "product": "firefox", 10:20:34 INFO - "script_repo_revision": "production", 10:20:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 10:20:34 INFO - "repository": "", 10:20:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 10:20:34 INFO - "buildid": "20160511073009", 10:20:34 INFO - "pgo_build": "True", 10:20:34 INFO - "basedir": "/builds/slave/test", 10:20:34 INFO - "buildnumber": 282, 10:20:34 INFO - "slavename": "talos-linux64-ix-017", 10:20:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:20:34 INFO - "platform": "ubuntu64_hw", 10:20:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 10:20:34 INFO - "branch": "mozilla-central", 10:20:34 INFO - "repo_path": "mozilla-central", 10:20:34 INFO - "revision": "3461f3cae78495f100a0f7d3d2e0b89292d3ec02", 10:20:34 INFO - "stage_platform": "linux64-pgo", 10:20:34 INFO - "builduid": "b71ac218f8c14983badea0d4759aed2e", 10:20:34 INFO - "slavebuilddir": "test" 10:20:34 INFO - } 10:20:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 10:20:34 INFO - Running post-action listener: _resource_record_post_action 10:20:34 INFO - ##### 10:20:34 INFO - ##### Finished read-buildbot-config step (success) 10:20:34 INFO - ##### 10:20:34 INFO - ##### 10:20:34 INFO - ##### Running download-and-extract step. 10:20:34 INFO - ##### 10:20:34 INFO - Running pre-action listener: _resource_record_pre_action 10:20:34 INFO - Running main action method: download_and_extract 10:20:34 INFO - mkdir: /builds/slave/test/build/tests 10:20:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:34 INFO - https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:20:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:20:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:20:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:20:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:20:35 INFO - Downloaded 1361 bytes. 10:20:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 10:20:35 INFO - Using the following test package requirements: 10:20:35 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:20:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:20:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:20:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'jsshell-linux-x86_64.zip'], 10:20:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:20:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 10:20:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:20:35 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:20:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:20:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 10:20:35 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:20:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:20:35 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')]} 10:20:35 INFO - https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:20:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:20:36 INFO - Downloaded 23903248 bytes. 10:20:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:20:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:20:37 INFO - Return code: 0 10:20:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:37 INFO - https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:20:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 10:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:20:38 INFO - Downloaded 23903248 bytes. 10:20:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:20:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 10:20:40 INFO - Return code: 0 10:20:40 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')]} 10:20:40 INFO - https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:20:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:20:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:20:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 10:20:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:20:41 INFO - Downloaded 10840152 bytes. 10:20:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:20:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 10:20:41 INFO - Return code: 0 10:20: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')]} 10:20:41 INFO - https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:20:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:20:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:20:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:20:42 INFO - Downloaded 58323329 bytes. 10:20:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:20:42 INFO - mkdir: /builds/slave/test/properties 10:20:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:20:42 INFO - Writing to file /builds/slave/test/properties/build_url 10:20:42 INFO - Contents: 10:20:42 INFO - build_url:https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 10:20:43 INFO - Running post-action listener: _resource_record_post_action 10:20:43 INFO - Running post-action listener: set_extra_try_arguments 10:20:43 INFO - ##### 10:20:43 INFO - ##### Finished download-and-extract step (success) 10:20:43 INFO - ##### 10:20:43 INFO - ##### 10:20:43 INFO - ##### Running populate-webroot step. 10:20:43 INFO - ##### 10:20:43 INFO - Running pre-action listener: _resource_record_pre_action 10:20:43 INFO - Running main action method: populate_webroot 10:20:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:20:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:20:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:20:43 INFO - '--tppagecycles', 10:20:43 INFO - '7'], 10:20:43 INFO - 'tests': ['tsvgm']}}, 10:20:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:20:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:20:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:20:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:20:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:20:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['damp', 'tps']}, 10:20:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'tests': ['damp', 'tps']}, 10:20:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['dromaeo_dom']}, 10:20:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:20:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'other-e10s': {'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['a11yr', 10:20:43 INFO - 'ts_paint', 10:20:43 INFO - 'tpaint', 10:20:43 INFO - 'sessionrestore', 10:20:43 INFO - 'sessionrestore_no_auto_restore', 10:20:43 INFO - 'tabpaint']}, 10:20:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['tsvgx', 10:20:43 INFO - 'tsvgr_opacity', 10:20:43 INFO - 'tart', 10:20:43 INFO - 'tscrollx', 10:20:43 INFO - 'cart']}, 10:20:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:20:43 INFO - 'tsvgr_opacity', 10:20:43 INFO - 'tart', 10:20:43 INFO - 'tscrollx', 10:20:43 INFO - 'cart']}, 10:20:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'talos_options': ['--disable-e10s'], 10:20:43 INFO - 'tests': ['tp5o']}, 10:20:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'tests': ['tp5o']}, 10:20:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'talos_options': ['--disable-e10s', 10:20:43 INFO - '--xperf_path', 10:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:43 INFO - 'tests': ['tp5n']}, 10:20:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:43 INFO - 'talos_options': ['--xperf_path', 10:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:43 INFO - 'tests': ['tp5n']}}, 10:20:43 INFO - 'talos.zip': {'path': '', 10:20:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:20:43 INFO - Running post-action listener: _resource_record_post_action 10:20:43 INFO - ##### 10:20:43 INFO - ##### Finished populate-webroot step (success) 10:20:43 INFO - ##### 10:20:43 INFO - ##### 10:20:43 INFO - ##### Running create-virtualenv step. 10:20:43 INFO - ##### 10:20:43 INFO - Running pre-action listener: _resource_record_pre_action 10:20:43 INFO - Running main action method: create_virtualenv 10:20:43 INFO - Creating virtualenv /builds/slave/test/build/venv 10:20:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:20:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:20:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:20:43 INFO - Using real prefix '/usr' 10:20:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:20:44 INFO - Installing distribute.............................................................................................................................................................................................done. 10:20:46 INFO - Installing pip.................done. 10:20:46 INFO - Return code: 0 10:20:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:20:46 INFO - Using env: {'DISPLAY': ':0', 10:20:46 INFO - 'HOME': '/home/cltbld', 10:20:46 INFO - 'LANG': 'en_US.UTF-8', 10:20:46 INFO - 'LANGUAGE': 'en_US:en', 10:20:46 INFO - 'LOGNAME': 'cltbld', 10:20:46 INFO - 'MAIL': '/var/mail/cltbld', 10:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:46 INFO - 'MOZ_NO_REMOTE': '1', 10:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:46 INFO - 'NO_EM_RESTART': '1', 10:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:46 INFO - 'PWD': '/builds/slave/test', 10:20:46 INFO - 'SHELL': '/bin/bash', 10:20:46 INFO - 'SHLVL': '1', 10:20:46 INFO - 'TERM': 'linux', 10:20:46 INFO - 'TMOUT': '86400', 10:20:46 INFO - 'USER': 'cltbld', 10:20:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:46 INFO - '_': '/tools/buildbot/bin/python'} 10:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:46 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:20:46 INFO - Cleaning up... 10:20:46 INFO - Return code: 0 10:20:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 10:20:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:20:46 INFO - Using env: {'DISPLAY': ':0', 10:20:46 INFO - 'HOME': '/home/cltbld', 10:20:46 INFO - 'LANG': 'en_US.UTF-8', 10:20:46 INFO - 'LANGUAGE': 'en_US:en', 10:20:46 INFO - 'LOGNAME': 'cltbld', 10:20:46 INFO - 'MAIL': '/var/mail/cltbld', 10:20:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:46 INFO - 'MOZ_NO_REMOTE': '1', 10:20:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:46 INFO - 'NO_EM_RESTART': '1', 10:20:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:46 INFO - 'PWD': '/builds/slave/test', 10:20:46 INFO - 'SHELL': '/bin/bash', 10:20:46 INFO - 'SHLVL': '1', 10:20:46 INFO - 'TERM': 'linux', 10:20:46 INFO - 'TMOUT': '86400', 10:20:46 INFO - 'USER': 'cltbld', 10:20:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:46 INFO - '_': '/tools/buildbot/bin/python'} 10:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:46 INFO - Downloading/unpacking psutil>=0.7.1 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:48 INFO - Installing collected packages: psutil 10:20:48 INFO - Running setup.py install for psutil 10:20:48 INFO - building 'psutil._psutil_linux' extension 10:20:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:20:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:20:48 INFO - building 'psutil._psutil_posix' extension 10:20:48 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:20:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:20:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:20:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:20:48 INFO - Successfully installed psutil 10:20:48 INFO - Cleaning up... 10:20:48 INFO - Return code: 0 10:20:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:20: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')]} 10:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20: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')]} 10:20:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 10:20:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:20:48 INFO - Using env: {'DISPLAY': ':0', 10:20:48 INFO - 'HOME': '/home/cltbld', 10:20:48 INFO - 'LANG': 'en_US.UTF-8', 10:20:48 INFO - 'LANGUAGE': 'en_US:en', 10:20:48 INFO - 'LOGNAME': 'cltbld', 10:20:48 INFO - 'MAIL': '/var/mail/cltbld', 10:20:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:48 INFO - 'MOZ_NO_REMOTE': '1', 10:20:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:48 INFO - 'NO_EM_RESTART': '1', 10:20:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:48 INFO - 'PWD': '/builds/slave/test', 10:20:48 INFO - 'SHELL': '/bin/bash', 10:20:48 INFO - 'SHLVL': '1', 10:20:48 INFO - 'TERM': 'linux', 10:20:48 INFO - 'TMOUT': '86400', 10:20:48 INFO - 'USER': 'cltbld', 10:20:48 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:48 INFO - '_': '/tools/buildbot/bin/python'} 10:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:20:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:20:50 INFO - Installing collected packages: mozsystemmonitor 10:20:50 INFO - Running setup.py install for mozsystemmonitor 10:20:51 INFO - Successfully installed mozsystemmonitor 10:20:51 INFO - Cleaning up... 10:20:51 INFO - Return code: 0 10:20:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:20: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')]} 10:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20: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')]} 10:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:20:51 INFO - Using env: {'DISPLAY': ':0', 10:20:51 INFO - 'HOME': '/home/cltbld', 10:20:51 INFO - 'LANG': 'en_US.UTF-8', 10:20:51 INFO - 'LANGUAGE': 'en_US:en', 10:20:51 INFO - 'LOGNAME': 'cltbld', 10:20:51 INFO - 'MAIL': '/var/mail/cltbld', 10:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:51 INFO - 'MOZ_NO_REMOTE': '1', 10:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:51 INFO - 'NO_EM_RESTART': '1', 10:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:51 INFO - 'PWD': '/builds/slave/test', 10:20:51 INFO - 'SHELL': '/bin/bash', 10:20:51 INFO - 'SHLVL': '1', 10:20:51 INFO - 'TERM': 'linux', 10:20:51 INFO - 'TMOUT': '86400', 10:20:51 INFO - 'USER': 'cltbld', 10:20:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:51 INFO - '_': '/tools/buildbot/bin/python'} 10:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:20:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:20:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20: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 10:20:53 INFO - Downloading docopt-0.6.1.tar.gz 10:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:20:53 INFO - Installing collected packages: blobuploader, requests, docopt 10:20:53 INFO - Running setup.py install for blobuploader 10:20:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:20:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:20:53 INFO - Running setup.py install for requests 10:20:54 INFO - Running setup.py install for docopt 10:20:54 INFO - Successfully installed blobuploader requests docopt 10:20:54 INFO - Cleaning up... 10:20:54 INFO - Return code: 0 10:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:20:54 INFO - Using env: {'DISPLAY': ':0', 10:20:54 INFO - 'HOME': '/home/cltbld', 10:20:54 INFO - 'LANG': 'en_US.UTF-8', 10:20:54 INFO - 'LANGUAGE': 'en_US:en', 10:20:54 INFO - 'LOGNAME': 'cltbld', 10:20:54 INFO - 'MAIL': '/var/mail/cltbld', 10:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:54 INFO - 'MOZ_NO_REMOTE': '1', 10:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:54 INFO - 'NO_EM_RESTART': '1', 10:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:54 INFO - 'PWD': '/builds/slave/test', 10:20:54 INFO - 'SHELL': '/bin/bash', 10:20:54 INFO - 'SHLVL': '1', 10:20:54 INFO - 'TERM': 'linux', 10:20:54 INFO - 'TMOUT': '86400', 10:20:54 INFO - 'USER': 'cltbld', 10:20:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:54 INFO - '_': '/tools/buildbot/bin/python'} 10:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:54 INFO - Running setup.py (path:/tmp/pip-4XhAxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:54 INFO - Running setup.py (path:/tmp/pip-7ExRPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:54 INFO - Running setup.py (path:/tmp/pip-z3hcTI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:54 INFO - Running setup.py (path:/tmp/pip-c5tGbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:54 INFO - Running setup.py (path:/tmp/pip-YsLsZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:54 INFO - Running setup.py (path:/tmp/pip-IICnND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:54 INFO - Running setup.py (path:/tmp/pip-dPHu6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:54 INFO - Running setup.py (path:/tmp/pip-D5oi0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:54 INFO - Running setup.py (path:/tmp/pip-GyY0_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:55 INFO - Running setup.py (path:/tmp/pip-ijsOmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:55 INFO - Running setup.py (path:/tmp/pip-BMSLXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:55 INFO - Running setup.py (path:/tmp/pip-sGd91j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:55 INFO - Running setup.py (path:/tmp/pip-E_gNf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:55 INFO - Running setup.py (path:/tmp/pip-fbhQSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:55 INFO - Running setup.py (path:/tmp/pip-J7gcaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:55 INFO - Running setup.py (path:/tmp/pip-BKmlcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:55 INFO - Running setup.py (path:/tmp/pip-n7RO_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:20:55 INFO - Running setup.py install for manifestparser 10:20:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:20:55 INFO - Running setup.py install for mozcrash 10:20:55 INFO - Running setup.py install for mozdebug 10:20:55 INFO - Running setup.py install for mozdevice 10:20:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:20:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:20:55 INFO - Running setup.py install for mozfile 10:20:55 INFO - Running setup.py install for mozhttpd 10:20:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozinfo 10:20:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozInstall 10:20:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozleak 10:20:56 INFO - Running setup.py install for mozlog 10:20:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for moznetwork 10:20:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozprocess 10:20:56 INFO - Running setup.py install for mozprofile 10:20:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozrunner 10:20:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:20:56 INFO - Running setup.py install for mozscreenshot 10:20:56 INFO - Running setup.py install for moztest 10:20:57 INFO - Running setup.py install for mozversion 10:20:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:20:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:20:57 INFO - Cleaning up... 10:20:57 INFO - Return code: 0 10:20:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:20:57 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')]} 10:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 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')]} 10:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:20:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:20:57 INFO - Using env: {'DISPLAY': ':0', 10:20:57 INFO - 'HOME': '/home/cltbld', 10:20:57 INFO - 'LANG': 'en_US.UTF-8', 10:20:57 INFO - 'LANGUAGE': 'en_US:en', 10:20:57 INFO - 'LOGNAME': 'cltbld', 10:20:57 INFO - 'MAIL': '/var/mail/cltbld', 10:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:57 INFO - 'MOZ_NO_REMOTE': '1', 10:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:57 INFO - 'NO_EM_RESTART': '1', 10:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:20:57 INFO - 'PWD': '/builds/slave/test', 10:20:57 INFO - 'SHELL': '/bin/bash', 10:20:57 INFO - 'SHLVL': '1', 10:20:57 INFO - 'TERM': 'linux', 10:20:57 INFO - 'TMOUT': '86400', 10:20:57 INFO - 'USER': 'cltbld', 10:20:57 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:57 INFO - '_': '/tools/buildbot/bin/python'} 10:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:20:57 INFO - Running setup.py (path:/tmp/pip-bhuzcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:20:57 INFO - Running setup.py (path:/tmp/pip-iPYaGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:20:57 INFO - Running setup.py (path:/tmp/pip-s3dOkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:20:57 INFO - Running setup.py (path:/tmp/pip-BiRKaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:20:57 INFO - Running setup.py (path:/tmp/pip-_1PdiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:20:57 INFO - Running setup.py (path:/tmp/pip-7YiIy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:20:57 INFO - Running setup.py (path:/tmp/pip-IOqUZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:20:57 INFO - Running setup.py (path:/tmp/pip-d5ct1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:20:57 INFO - Running setup.py (path:/tmp/pip-6l5fX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:20:57 INFO - Running setup.py (path:/tmp/pip-CUELUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:20:57 INFO - Running setup.py (path:/tmp/pip-1UlxQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:20:57 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)) 10:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:20:57 INFO - Running setup.py (path:/tmp/pip-LU5bVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:20:58 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)) 10:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:20:58 INFO - Running setup.py (path:/tmp/pip-UBraTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:20:58 INFO - Running setup.py (path:/tmp/pip-HsWPhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:20:58 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)) 10:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:58 INFO - Running setup.py (path:/tmp/pip-knSJes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:20:58 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)) 10:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:20:58 INFO - Running setup.py (path:/tmp/pip-iXD8pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:20:58 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)) 10:20:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:20:58 INFO - Running setup.py (path:/tmp/pip-ihYzJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:20:58 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)) 10:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:20:58 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)) 10:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:20:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:20:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:00 INFO - Downloading blessings-1.6.tar.gz 10:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:21:00 INFO - Installing collected packages: blessings 10:21:00 INFO - Running setup.py install for blessings 10:21:00 INFO - Successfully installed blessings 10:21:00 INFO - Cleaning up... 10:21:00 INFO - Return code: 0 10:21:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:00 INFO - Reading from file tmpfile_stdout 10:21:00 INFO - Current package versions: 10:21:00 INFO - argparse == 1.2.1 10:21:00 INFO - blessings == 1.6 10:21:00 INFO - blobuploader == 1.2.4 10:21:00 INFO - docopt == 0.6.1 10:21:00 INFO - manifestparser == 1.1 10:21:00 INFO - mozInstall == 1.12 10:21:00 INFO - mozcrash == 0.17 10:21:00 INFO - mozdebug == 0.1 10:21:00 INFO - mozdevice == 0.48 10:21:00 INFO - mozfile == 1.2 10:21:00 INFO - mozhttpd == 0.7 10:21:00 INFO - mozinfo == 0.9 10:21:00 INFO - mozleak == 0.1 10:21:00 INFO - mozlog == 3.1 10:21:00 INFO - moznetwork == 0.27 10:21:00 INFO - mozprocess == 0.22 10:21:00 INFO - mozprofile == 0.28 10:21:00 INFO - mozrunner == 6.11 10:21:00 INFO - mozscreenshot == 0.1 10:21:00 INFO - mozsystemmonitor == 0.0 10:21:00 INFO - moztest == 0.7 10:21:00 INFO - mozversion == 1.4 10:21:00 INFO - psutil == 3.1.1 10:21:00 INFO - requests == 1.2.3 10:21:00 INFO - wsgiref == 0.1.2 10:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:21:00 INFO - Using env: {'DISPLAY': ':0', 10:21:00 INFO - 'HOME': '/home/cltbld', 10:21:00 INFO - 'LANG': 'en_US.UTF-8', 10:21:00 INFO - 'LANGUAGE': 'en_US:en', 10:21:00 INFO - 'LOGNAME': 'cltbld', 10:21:00 INFO - 'MAIL': '/var/mail/cltbld', 10:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:00 INFO - 'MOZ_NO_REMOTE': '1', 10:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:00 INFO - 'NO_EM_RESTART': '1', 10:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:00 INFO - 'PWD': '/builds/slave/test', 10:21:00 INFO - 'SHELL': '/bin/bash', 10:21:00 INFO - 'SHLVL': '1', 10:21:00 INFO - 'TERM': 'linux', 10:21:00 INFO - 'TMOUT': '86400', 10:21:00 INFO - 'USER': 'cltbld', 10:21:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:00 INFO - '_': '/tools/buildbot/bin/python'} 10:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:21:00 INFO - Cleaning up... 10:21:00 INFO - Return code: 0 10:21:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:21:00 INFO - Using env: {'DISPLAY': ':0', 10:21:00 INFO - 'HOME': '/home/cltbld', 10:21:00 INFO - 'LANG': 'en_US.UTF-8', 10:21:00 INFO - 'LANGUAGE': 'en_US:en', 10:21:00 INFO - 'LOGNAME': 'cltbld', 10:21:00 INFO - 'MAIL': '/var/mail/cltbld', 10:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:00 INFO - 'MOZ_NO_REMOTE': '1', 10:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:00 INFO - 'NO_EM_RESTART': '1', 10:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:00 INFO - 'PWD': '/builds/slave/test', 10:21:00 INFO - 'SHELL': '/bin/bash', 10:21:00 INFO - 'SHLVL': '1', 10:21:00 INFO - 'TERM': 'linux', 10:21:00 INFO - 'TMOUT': '86400', 10:21:00 INFO - 'USER': 'cltbld', 10:21:00 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:00 INFO - '_': '/tools/buildbot/bin/python'} 10:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:00 INFO - Downloading/unpacking jsonschema 10:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:02 INFO - Downloading/unpacking functools32 (from jsonschema) 10:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:21:03 INFO - warning: no files found matching '*.txt' 10:21:03 INFO - no previously-included directories found matching 'build' 10:21:03 INFO - no previously-included directories found matching 'dist' 10:21:03 INFO - no previously-included directories found matching '.git*' 10:21:03 INFO - Installing collected packages: jsonschema, functools32 10:21:03 INFO - Running setup.py install for functools32 10:21:03 INFO - warning: no files found matching '*.txt' 10:21:03 INFO - no previously-included directories found matching 'build' 10:21:03 INFO - no previously-included directories found matching 'dist' 10:21:03 INFO - no previously-included directories found matching '.git*' 10:21:03 INFO - Successfully installed jsonschema functools32 10:21:03 INFO - Cleaning up... 10:21:03 INFO - Return code: 0 10:21:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:21:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbea43baf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x200e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d9770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, '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': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', '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 10:21:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 10:21:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:21:03 INFO - Using env: {'DISPLAY': ':0', 10:21:03 INFO - 'HOME': '/home/cltbld', 10:21:03 INFO - 'LANG': 'en_US.UTF-8', 10:21:03 INFO - 'LANGUAGE': 'en_US:en', 10:21:03 INFO - 'LOGNAME': 'cltbld', 10:21:03 INFO - 'MAIL': '/var/mail/cltbld', 10:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:03 INFO - 'MOZ_NO_REMOTE': '1', 10:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:03 INFO - 'NO_EM_RESTART': '1', 10:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:03 INFO - 'PWD': '/builds/slave/test', 10:21:03 INFO - 'SHELL': '/bin/bash', 10:21:03 INFO - 'SHLVL': '1', 10:21:03 INFO - 'TERM': 'linux', 10:21:03 INFO - 'TMOUT': '86400', 10:21:03 INFO - 'USER': 'cltbld', 10:21:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:03 INFO - '_': '/tools/buildbot/bin/python'} 10:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:03 INFO - Downloading/unpacking flake8 10:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:21:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:21:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:21:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:21:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:21:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:21:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:21:06 INFO - no previously-included directories found matching 'docs/_build' 10:21:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:21:06 INFO - Running setup.py install for pep8 10:21:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:21:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:21:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:21:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:21:06 INFO - no previously-included directories found matching 'docs/_build' 10:21:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:21:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:21:06 INFO - Cleaning up... 10:21:06 INFO - Return code: 0 10:21:06 INFO - Running post-action listener: _resource_record_post_action 10:21:06 INFO - Running post-action listener: _start_resource_monitoring 10:21:06 INFO - Starting resource monitoring. 10:21:06 INFO - ##### 10:21:06 INFO - ##### Finished create-virtualenv step (success) 10:21:06 INFO - ##### 10:21:06 INFO - ##### 10:21:06 INFO - ##### Running install step. 10:21:06 INFO - ##### 10:21:06 INFO - Running pre-action listener: _resource_record_pre_action 10:21:06 INFO - Running main action method: install 10:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:21:06 INFO - Reading from file tmpfile_stdout 10:21:06 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:21:06 INFO - Reading from file tmpfile_stdout 10:21:06 INFO - Output received: 10:21:06 INFO - Usage: mozinstall [options] installer 10:21:06 INFO - Options: 10:21:06 INFO - -h, --help show this help message and exit 10:21:06 INFO - -d DEST, --destination=DEST 10:21:06 INFO - Directory to install application into. [default: 10:21:06 INFO - "/builds/slave/test"] 10:21:06 INFO - --app=APP Application being installed. [default: firefox] 10:21:06 INFO - mkdir: /builds/slave/test/build/application 10:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:21:23 INFO - Reading from file tmpfile_stdout 10:21:23 INFO - Output received: 10:21:23 INFO - /builds/slave/test/build/application/firefox/firefox 10:21:23 INFO - Running post-action listener: _resource_record_post_action 10:21:23 INFO - ##### 10:21:23 INFO - ##### Finished install step (success) 10:21:23 INFO - ##### 10:21:23 INFO - ##### 10:21:23 INFO - ##### Running run-tests step. 10:21:23 INFO - ##### 10:21:23 INFO - Running pre-action listener: _resource_record_pre_action 10:21:23 INFO - Running main action method: run_tests 10:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:21:23 INFO - Python 2.7.3 10:21:23 INFO - Return code: 0 10:21:23 INFO - grabbing minidump binary from tooltool 10:21: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')]} 10:21:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21689c0>, '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 0x21698c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2169d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:21:23 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 10:21:23 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 10:21:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:21:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:21:23 INFO - Return code: 0 10:21:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:21:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:21:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:21:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:21:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:21:24 INFO - Return code: 0 10:21:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:21:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:21:24 INFO - Using env: {'DISPLAY': ':0', 10:21:24 INFO - 'HOME': '/home/cltbld', 10:21:24 INFO - 'LANG': 'en_US.UTF-8', 10:21:24 INFO - 'LANGUAGE': 'en_US:en', 10:21:24 INFO - 'LOGNAME': 'cltbld', 10:21:24 INFO - 'MAIL': '/var/mail/cltbld', 10:21:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:21:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:24 INFO - 'MOZ_NO_REMOTE': '1', 10:21:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:21:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:21:24 INFO - 'NO_EM_RESTART': '1', 10:21:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:21:24 INFO - 'PWD': '/builds/slave/test', 10:21:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:21:24 INFO - 'SHELL': '/bin/bash', 10:21:24 INFO - 'SHLVL': '1', 10:21:24 INFO - 'TERM': 'linux', 10:21:24 INFO - 'TMOUT': '86400', 10:21:24 INFO - 'USER': 'cltbld', 10:21:24 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021', 10:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:21:24 INFO - '_': '/tools/buildbot/bin/python'} 10:21:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:21:24 INFO - mozversion application_buildid: 20160511073009 10:21:24 INFO - mozversion application_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 10:21:24 INFO - mozversion application_display_name: Nightly 10:21:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:24 INFO - mozversion application_name: Firefox 10:21:24 INFO - mozversion application_remotingname: firefox 10:21:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:21:24 INFO - mozversion application_vendor: Mozilla 10:21:24 INFO - mozversion application_version: 49.0a1 10:21:24 INFO - mozversion platform_buildid: 20160511073009 10:21:24 INFO - mozversion platform_changeset: 3461f3cae78495f100a0f7d3d2e0b89292d3ec02 10:21:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:21:24 INFO - mozversion platform_version: 49.0a1 10:21:24 INFO - using testdate: 1462987284 10:21:24 INFO - actual date: 1462987284 10:21:24 INFO - starting webserver on 'localhost:44184' 10:21:24 INFO - SUITE-START | Running 2 tests 10:21:24 INFO - TEST-START | dromaeo_css 10:21:24 INFO - operating with platform_type : linux_ 10:21:24 INFO - Initialising browser for dromaeo_css test... 10:21:24 INFO - TEST-INFO | started process 7278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDpzGY6/profile http://localhost:44184/getInfo.html) 10:21:27 INFO - PROCESS | 7278 | __metrics Screen width/height:1600/1200 10:21:27 INFO - PROCESS | 7278 | colorDepth:24 10:21:27 INFO - PROCESS | 7278 | Browser inner width/height: 1024/697 10:21:27 INFO - PROCESS | 7278 | __metrics 10:21:28 ERROR - PROCESS | 7278 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:21:29 INFO - TEST-INFO | 7278: exit 0 10:21:29 INFO - Browser initialized. 10:21:29 INFO - Running cycle 1/1 for dromaeo_css test... 10:21:29 INFO - TEST-INFO | started process 7356 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDpzGY6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:21:40 INFO - PROCESS | 7356 | RSS: Main: 151322624 10:21:40 INFO - PROCESS | 7356 | 10:22:31 INFO - PROCESS | 7356 | 1462987351319 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] 10:24:38 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44184/tests/dromaeo/cssquery-ext.html) 10:24:39 INFO - PROCESS | 7356 | RSS: Main: 255090688 10:24:39 INFO - PROCESS | 7356 | 10:27:42 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-ext.html (next: http://localhost:44184/tests/dromaeo/cssquery-jquery.html) 10:27:42 INFO - PROCESS | 7356 | RSS: Main: 237162496 10:27:42 INFO - PROCESS | 7356 | 10:30:44 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44184/tests/dromaeo/cssquery-mootools.html) 10:30:45 INFO - PROCESS | 7356 | RSS: Main: 237981696 10:30:45 INFO - PROCESS | 7356 | 10:33:47 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44184/tests/dromaeo/cssquery-prototype.html) 10:33:47 INFO - PROCESS | 7356 | RSS: Main: 227295232 10:33:47 INFO - PROCESS | 7356 | 10:36:50 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44184/tests/dromaeo/cssquery-yui.html) 10:36:50 INFO - PROCESS | 7356 | RSS: Main: 218820608 10:36:50 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | Cycle 1(1): loaded http://localhost:44184/tests/dromaeo/cssquery-yui.html (next: http://localhost:44184/tests/dromaeo/cssquery-dojo.html) 10:39:53 INFO - PROCESS | 7356 | RSS: Main: 265412608 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | __start_tp_report 10:39:53 INFO - PROCESS | 7356 | _x_x_mozilla_page_load 10:39:53 INFO - PROCESS | 7356 | _x_x_mozilla_page_load_details 10:39:53 INFO - PROCESS | 7356 | |i|pagename|runs| 10:39:53 INFO - PROCESS | 7356 | |0;dojo.html;2967.6438827340717;3001.5197568389062;3022.8639711212995;3055.0834749575124;3065.5095184770425;4904;4909.705417674942;5067.315912351836;5101.438841727411;5107.565856902159;4863.902721945559;5131.3842338919385;5132.307138536296;5143.074246635604;5211.192265198891;4621.838235661752;4644.5123262057505;4683.672142949995;4750.239961606144;4799.232122860343;4738.981119059402;4761.47623761386;4771.785269662873;4804.6876952997945;4820.228763397857;4509.706869053501;4633.930491042637;4658.487566367697;4678.953210467886;4690.061987602493;4642.210824159867;4660.86017419478;4670.673052886299;4674.509176536482;4696.201645720235;4510.797014134372;4566.041131362418;4615.446146462413;4640.048789998076;4668.649851261135;5012.548870601627;5062.544371006627;5067.695938243717;5068.037072956164;5086.287919691246;5489.780408783644;5493.533049690781;5504.559635229172;5524.116141417394;5545.500904918576;5263.421023687391;5421.728913554307;5423.186522021729;5424.105022671294;5465.344158700942;5191.974040129775;5201.297824793643;5202.635815492879;5203.661761985497;5212.400573934008;5662.178984047372;5674.829755107354;5679.545636349082;5680;5680.6591604503865;5270.552003079782;5286.656367336111;5338.3593968724;5339.626226164131;5449.918251226235;5395.433479484676;5424.972875135599;5435.320584926884;5453.4273901240595;5490.780368785284;5354.330708661418;5367.570594352443;5376.892462150735;5394.622376433612;5420.132778755459;5211.661242019257;5268.92096618551;5330.120530112566;5350.839474815684;5352.652077614943;4119.423280740699;4121.649659778893;4122.2579935611275;4125.319322311785;4157.210130075276;3680.024793429743;3713.7586056906216;3716.3682174030478;3735.5143831301757;3735.9813200934364;5075.264086707481;5098.617603679739;5101.591872650179;5140.922886156711;5143.614228932845;4927.260910863399;5281.0758117329315;5403.70279634616;5406.162044883049;5427.402985671573;4936.753162341868;5031.245313203049;5092.388913330424;5097.566706829961;5104.259882317043;4480.081583275421;4506.504284528699;4509.864704058883;4548.2722764358205;4559.954400456019;5571.582131340166;5595;5598;5619.943800562024;5621.381648018714;5249.055170069424;5291.417944026231;5330.573554115739;5412.377576578665;5433.483819037185;5479.315085614298;5482.643628164156;5509.779608815763;5513.3108361454815;5533.280673512418;5245.370555533361;5286.444923283;5299.735013249399;5301.761420735999;5344.946550534445;5234.424213336606;5303.708296043755;5349.812756553502;5369.033573956725;5370.060239458157;5245.501677340647;5272.736363181749;5274.4198138204;5289.788408463618;5314.813981510629;5239.869003274948;5290.25936368901;5299.205119232146;5311.920321195263;5334.546563542142;5006.998600279886;5085.974570127126;5149.819756308511;5167.896642067213;5233.633645644921;5312.070387682063;5327.600429967845;5333.919991200058;5350.919736204038;5400.62195646301;5078.822241221539;5265.368155821173;5285.418603953639;5297.205419186998;5341.092014357491 10:39:53 INFO - PROCESS | 7356 | |1;ext.html;574.9741261643228;598.4015984015984;601.0683440666966;610.8869859076071;613.840401495611;31978.520322195185;43175.35236971444;43656.78171609138;43716.34425483616;43987.78006109973;44276;44709.32936005962;44713.329300060526;44782.104357912904;44810;7766.417518686121;8679.73960781176;8825;8844.336674749387;10168;9925.454100024495;10283.074523292902;10289.89057243267;10301.27891047627;10307.793844123113;10344.37933723979;10370.9481452593;10375.84436233457;10387.688369348894;10389.42858142802;7965.203479652047;10222.795544089076;10308.278420510529;10451.477426128664;10455.529501172465;10292.382457052527;10327.690169294932;10332;10341.828216074633;10347.068763811292;40712.18575628471;40891.386629200584;40918.59081409207;41056;41071.58928410737;3483.407820670492;3598.24436868258;3653.6711695947492;3780.0360907968293;4229.7462152270655;31096.222594435094;31201.375972480422;31230.687693123004;31550.211244718837;32285.83857080723;4252.893677658052;4409.162259170779;4409.536998615131;4424.446944131983;4580.541945805426;319304;319888;320204;320937;321927.39036304905;34208.315833683184;35127.4730879037;35148;37045.073873153386;55349.446505534426;35975.4603680945;35986.460203096976;36101.819490902904;36115.27769444596;36141.277174456365;15298.158601276815;15388.769168462482;15394.6151346215;15408.537743867702;15432.074075555503;13075.738485230242;13109.541166059142;13144.27706476153;13146.342682865818;13268.734625307632;8373.41386102979;8408.873866892001;8451.323894088458;8455.788605284917;8496.872546911802;3114.3304189599344;3230.6930841570015;3306.7519936004896;3325.7029758394247;3343.4483310253536;1061.7345663584103;1122.9500417110028;1134.9092072634169;1180.032373453777;1223.8409006829233;31190;31416.37167256642;31471.52792708111;31745.365092698015;31755.20611984719;1121.0807138146804;1144.067584918289;1184.4314728930162;1196.7845787758288;1230.655416483386;16406.09766462856;16682.416115435903;16811.99128052321;16839.663206735797;17022.829771702123;470514;471444;472128;474671;476586;37750.811245943594;54858;55208.44791552114;55405.99999999919;55421.44578554243;1368.2748143483968;1396.818413606245;1408.704172123845;1413.1168019987713;1419.332913530639;29095.418091637624;29392.11823645294;30386.84806575953;30503.389932201677;30925.536116958712;47861.52138478571;48183;48206.75896620494;48348.75825620849;48538;387922;389507;390837;391021;392732;24227.51544969126;26792.062277820183;26995.05517306885;28049.298767530974;29335.413291734472;45917.31124033075;46407.83980400382;46448;46806.06387872292;46907.76546117248;37642.623573763914;38301;38598;38623.22753544857;38711.6128838708;39190.41214381844;39498.40752388659;39713.40429893612;39820.601793982845;40165;39459.60540394559;39944.60055399525;40150;40157.79921100376;40497.79751101226;32860.50709239411;33463.49804752979;33605.32789344151;34271.314573708885;34606.30787384289;26681.733182667926;26864.462710746066;27161.59257611098;27271.181864544094;27602 10:39:53 INFO - PROCESS | 7356 | |2;jquery.html;399.1757021750086;404.2581862282711;406.31941497990863;409.3450479233229;412.126292260408;13925.30373481327;17023.489295321127;17290.39483618072;18805.341813036546;18844.246230150795;15029;17482.562935926577;18188;18349;18938;5423.918641220384;5629.099344104944;5650.6892120933335;5655.745491452874;5659.801906933259;4030.637242648161;5597.244372009787;5616.269884914956;5858.502027327661;6244.906326405107;6245.594036387621;6282.371762823704;6293.4335909768115;6307.337729538402;6320.873582528347;6004.759809607611;6134.662593557353;6185.783497577609;6189.659568723719;6198.44214020738;5256.605754568384;5431.402545719968;5478.671279723229;5485.122380419114;5485.9725701371235;22424.327270181922;22498.66252006221;22701.54596908069;22903.88548057266;23157;2295.403195169268;2494.912678056259;2652.0982865825713;2668.2262143978223;2820.9280473420176;13321.866781332159;14066;14131.858681413158;14206.868969654635;14678.779818302733;1926.7976862429384;1970.812772786583;1981.5145289404045;2020.3534868841975;2077.7610574783944;46936;57798.71100644512;71417.64291178563;71887.64056179738;74207;10276.229282803819;10478.842817357594;10691.73270668224;10691.893081069089;10715.785684286426;14670;14716.926415368069;14738.55784326472;14818.555443336718;14861.925690371696;8139.348852091818;8151.225633564802;8168.305694015957;8257.133001034925;8276.793080172924;7073.858522829514;7961.323287520509;7970.202979701983;7972.561509117054;8074.111847696749;6153.753849845956;6462.709178086903;6567.934320656828;6630;6638.805015097232;1952.194220346174;1960.607878424321;1961.1567026178595;1965.0764140853776;1985.6993669146732;921.7326975177124;1003.2926786615419;1090.7763908398758;1104.376027544435;1120.6525976947173;18090;18090.768385840584;18600.06999650039;19187.712184317246;19257.22971081169;1013.3008224325192;1047.2669131607904;1051.6319288249204;1067.9626213082504;1117.402189828438;10514.631987880388;11070.28043177191;11103.72240693989;11180.10559155282;11198.440077996067;453974;477456;477577;477728;505559;74886.25113748793;78455;78799.60600197077;79357.60321198357;79450.6027469859;1056.8837427882927;1064.270974382566;1067.5836423794726;1095.8465814785936;1101.0200921180156;16393.13177079947;16502.504924852274;16665.91667041657;16816.411425600047;17010.74483882718;24932.875335623205;25206.873965630053;33440.49839252462;33826.49260261049;35667.10832229215;47723;48065.75967120142;49489;49690.50309496859;50718.23922641089;8448.155184481502;8511.06378298399;8680.78298042554;8757.430767000125;8868.201861832466;12516.249025058525;13008.699130087294;13348.199108053548;13424.798628020391;13758;11219.326840389604;11296.209265351676;11406.859314068526;11850.170488065753;11927.165098443025;11348.375839328915;11460.312381257152;11507.252028618113;11905.166638335233;11961.64115076549;11501.597444089417;11623.302601843916;11761;11811.527538898348;11974.461149248478;7362.337389634744;7658.655360508902;8467.576621168793;8472;8591.613377397907;11266.830997535208;11333.829992550283;11528.654140375802;11572.18994670365;11800.586979456022 10:39:53 INFO - PROCESS | 7356 | |3;mootools.html;1338.504753241301;1499.445205274048;1532.432999790078;1713.5716070982255;1861.8603604729649;7983.441159118864;8342.874856877144;8378.413511054228;8482.53346065966;8548.70079547216;8273.669053237878;8408.453450525727;8434.746957591267;8452.619632116553;8470.745877623682;1902.258119333461;2157.147926569004;2225.354647152324;2252.526969336441;2275.544891021794;2256.8307376946714;2260.7513173550897;2267.841251112423;2274.4768703198274;2279.783420575051;2214.3356992902145;2258.4128126687015;2263.6265016272296;2265.4336415896028;2269.4553307206234;1837.2742766607182;2204.1403852497538;2268.1267711931027;2275.180934863448;2280.4754906371627;2222.655488399301;2230.721159855018;2238.7985081342595;2247.5729611373786;2274.4541310085624;8487.490750554925;8589.527575983318;8603.268722158624;8612.48325100496;8618.310535157172;4959.578435832958;5115.104856650071;5133.820316288912;5146.742662866879;5163.14808056674;3311.2880730642787;3324.434846076173;3411.181316484051;3427.365937301607;3433.776804507699;1092.7978324010085;1124.3928278729477;1130.5816847766296;1132.7054965708892;1137.5108703257597;11857.762844743056;11882.821757673642;11889.583864564698;11908.225965312227;11919.702007449883;4394.648428125743;4430.3575981483145;4506.59440650343;4520.773961301922;4531.864044078683;9877.456739879231;9937.254705897087;9956.153726933291;9976.750581235527;9994.800103997879;4672.556107169813;5036.84889453317;5108.131617625013;5113.3097031900215;5168.715720635401;4604.58558729716;4688.8124475021295;4745.3593764842;4748.311494833299;4760.404949381327;3844.5002149720917;3850.2876967761053;3898.2593307271527;3923.9993801580413;3983.42240375144;1231.3043130631165;1231.5012419969926;1234.5247079874314;1237.307108019512;1265.1270623269913;921.8525035994209;956.1681337236648;960.4333443268505;961.274237950343;969.1665167955553;5622.100463925753;5623.353314368818;5631.436856314336;5678.261825962599;5798.9130163047585;447.9910401791946;463.4670129351206;464.1621872520066;465.6507619285536;483.3836858006;3772.2455508897783;3851.441540976599;3873.1479074604104;3878.495795546561;3923.156521347924;10226.846597301046;10571.682849514527;10607.734806629742;10707.464626768631;10722.517486713117;10228.335158214692;10502;10590.735231619272;10599.682009539727;10769;8008;8041.67833286662;8081.838363232702;8107;8209.343252539782;5824.796132135524;5898.262717160355;5926.377730338252;5933.199018132497;5945.10823376497;9558.426494410358;9866;9935.304528682922;9968.352057116264;10006.09945104944;9775.951120244354;10084.193264538673;10177.694669159937;10355;10358.171346292127;7297.708091676273;7330.633468326669;7358.190599034209;7373.15208750979;7387.261273872569;8062;8077.15189905075;8133.918660813316;8146.674133034612;8152.551609661526;7594.164641889498;7831.490953088031;7905.723299684483;7936.404769642185;8046.195380462141;7547.5848828314965;7585.127710313384;7735.613219338898;7750.92249077502;7836.138024817379;6579.703913324008;6684.097646817811;6724.29394913527;6778.288279730557;6842.521023528305;4887.437944636412;5037.967216720637;5165.845024649267;5209.453007434165;5242.318498595158;5219.556337711337;5250.684958902478;5276.103062479311;5294.100002999577;5319.813806516753 10:39:53 INFO - PROCESS | 7356 | |4;prototype.html;338.7764075710032;341.62933217459056;346.6550781971938;347.56901442211665;348.53818690235437;5143.688359468335;5177.5599074078655;5207.973960130199;5232.581393488521;5246.37043554773;5091.287219789232;5113.411957624874;5128.871778205537;5156.639035267533;5173.198154286082;3922.960770392295;3929.5481019682816;4006.7996600169877;4033.616806403387;4046.676265898736;3998.6801055915457;4023.7786921719294;4024.531046168142;4030.8387664493384;4081.3265811141127;3830.885073447787;4057.4928133983253;4058.391241313797;4064.288749468831;4089.672826173899;3978.6021397860127;4034.4351790749024;4038.1116154445976;4040.4141399496907;4090.550039495653;3588.22853086585;3612.584552776438;3615.3311637347174;3632.54593175853;3642.508261384732;4965.453800081988;5003.9241563064115;5012.674176178537;5016.623753218487;5017.172166592508;4542.863714088583;4590.806390338536;4615.699979501355;4619.445666520006;4633.6988095774;5238.05714971305;5266.368035835686;5336.199570064483;5346.866328341823;5366.6511676741275;3991.2815693175216;3995.98002009991;3996.5603783583783;4006.759594424344;4008.7995600219874;5313.814016509442;5508.834734957958;5513.338399392099;5531.889362212733;5548.889022219573;3983.780892050907;3986.86045988389;4000.739951903117;4030.214108248922;4063.187362527506;4057.736247143926;4098;4098.426220329156;4108.91782164361;4115.691323150776;4486.685931984795;4582.991741816795;4599.816007359668;4604.332371806069;4614.446266448048;5347.866303342447;5357.973210133924;5365.275687782178;5374.1938709193155;5398.055340315429;1418.4964337660188;1439.9352029158713;1449.152245936118;1450.4778279819257;1454.6508837878832;947.6590624266586;952.8189643967625;983.2674657380154;995.9402435853872;1003.7289931718395;4555.931661025087;4782.780391000273;5114.363403710777;5161.845144645667;5200.7139607321205;5489.835304940778;5504.156107703884;5504.4862662768055;5519.641223320471;5570.108782594765;4960.206366981266;5101.311322971352;5117.616178786606;5128.897422051538;5133.820316288912;2840.928976775597;2852.6164810067103;2878.747744731049;2900.753435957925;2908.7527560157623;5336.332958380202;5506.421825708323;5513.062779327443;5516.613837031449;5571.025070612626;5363.785448582091;5435.22803370284;5442.91835622466;5450.972745136328;5494.560435165177;5455.454454554592;5515.696636685021;5587.189857470563;5593.580481463825;5639.746211420496;5227;5318.547923426398;5394.163904594794;5480.534154596904;5483.341998960151;5304.363476382704;5321.680699158062;5332.520073193431;5350.571954243729;5400.567954563547;5481.5340696041285;5496.945030549803;5556.38879723222;5560.833175004756;5590.385057643737;3941.3299739045005;4016.4376987222395;4043.1711499143194;4047.57500462459;4049.5747946466377;5333.719907222161;5368.269053008122;5373.2746079278795;5376.8118115865755;5379.623426360274;5254.605904557097;5259.921101183409;5325.653832500875;5332.466753324637;5337.386200586982;5068.9239661405845;5298.629095963245;5395.622306438669;5397.136458166675;5404.486573775485;5019.272205530251;5110.207917772751;5213.765380557808;5214.366744794006;5262.842114736564;5394.947985143016;5466.972665136649;5483.177523371526;5486.31421072366;5492.258545096361;5294.179402192666;5302.469753024667;5313.495217954288;5319.920201197063;5325.813596524103 10:39:53 INFO - PROCESS | 7356 | |5;yui.html;762.7521055656913;767.658392015553;818.6929901287017;821.8397412504561;849.6728759427621;13626.659333516667;14468.92765536171;14501.274936253185;14513.5645930622;14532.128072315669;12766.553170639007;12865.678358041054;13318;13338.66653333667;13347.065705400626;829.0548774397192;834.929031032363;835.9874601880977;837.6523742646795;838.2581415447312;835.5195762436593;839.1482645115213;840.1514470384925;842.8356470488258;843.5866425451516;873.1574031060026;874.5714599846061;874.9693760718376;880.8150288439435;882.6116508736136;842.0274582856811;851.7785375802274;870.8954925408959;871.7559083456642;872.271653169601;637.9489640828723;639.8656282180748;640.2157357237394;640.6700549217157;641.1120597971797;12228.877711222864;12525.123241373107;12565.811512827315;12568.497260109585;12635.11554191207;11521.942390288079;11735.647930562096;11871.643850684493;11922.344271065089;12190.085743569267;10240.231982601335;10326.483675816255;10353.53409096585;10542.10392116671;10598.470076496222;3629.7277704172293;3686.189038411545;3731.9067023324633;3877.592852750477;3892.2215556888455;42087.789561052;42483;49135.75432122888;49176.016479670565;51713.482865171245;8827;9864.654737084167;10407.53166107527;10907.454627268606;11113.611023614134;17713.468595942144;17946.730799038025;18594.163262652943;18594.442166735018;18668.719969200472;8252.8349433011;8362.080171181287;8362.498250105013;8544.914550854537;8668.306535477273;4625.7687115644085;4673.813047478131;4696.8590942271785;4724.196886529298;4837.080954618611;3152.6059242594674;3257.5928008998876;3272.3618894315855;3286.0963235110535;3289.851956661956;616.3282022595392;626.8119564130742;632.0172132334172;640.8077576726963;652.2531701202097;682.1336902134361;740.6187460386341;754.7767077688552;759.3128218961738;766;9448.385854919408;9481.383710058824;9498.952505237568;9618.422894626345;9764.22627136298;615.0497481391261;663.3896814930275;757;762.881753328235;768.8308572114126;4155.252054630135;4498.707584007095;4516.706414083074;4534.954650453519;4636.235021221461;57329.42670573241;58449;58801.41198588045;59157;59384;43002.35496467556;51581;53887.19169212465;54277.45722542803;54398.45601544013;48597.271040934414;52933.47066529362;54112.45887541153;54274.728626357406;54584;9438;9468.763280917756;9557.415969011621;9692.41845489273;9735.269854761063;31828.68171318257;33474.497882531294;34899.65100349065;34963.300733984666;35209.295814084086;41579.37630935599;46312.53687463082;46892.53107468881;47004.7649761749;47315.290270646656;12115.818262726243;12442.315672638091;12534.185277956904;12641.620751377473;12886.67783305425;13041.217526948414;13133.540326088541;13136.211827290394;13254.80117798253;13660;12955.805662914876;13016.93491532574;13044.217346959213;13250.734985300047;13520.25638589887;12660.430280637578;13015.739685206054;13156.162744315687;13311.733765324832;13860.653483662585;12335.321557314433;12672.74654506923;12838.871611284141;13097.869021309665;13562.186268823902;9601.47191904452;10354.430506322225;10564.78931833053;10577.788444231226;10597.41714205726;10636.84044739314;10775.515101820594;10828.512716927915;10856.294340867818;10999.175061870552 10:39:53 INFO - PROCESS | 7356 | __end_tp_report 10:39:53 INFO - PROCESS | 7356 | __start_cc_report 10:39:53 INFO - PROCESS | 7356 | _x_x_mozilla_cycle_collect,765 10:39:53 INFO - PROCESS | 7356 | __end_cc_report 10:39:53 INFO - PROCESS | 7356 | __startTimestamp1462988393787__endTimestamp 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | ------- Summary: start ------- 10:39:53 INFO - PROCESS | 7356 | Number of tests: 6 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#0] dojo.html Cycles:165 Average:5030.96 Median:5233.63 stddev:553.34 (10.6%) stddev-sans-first:530.83 10:39:53 INFO - PROCESS | 7356 | Values: 2967.6 3001.5 3022.9 3055.1 3065.5 4904.0 4909.7 5067.3 5101.4 5107.6 4863.9 5131.4 5132.3 5143.1 5211.2 4621.8 4644.5 4683.7 4750.2 4799.2 4739.0 4761.5 4771.8 4804.7 4820.2 4509.7 4633.9 4658.5 4679.0 4690.1 4642.2 4660.9 4670.7 4674.5 4696.2 4510.8 4566.0 4615.4 4640.0 4668.6 5012.5 5062.5 5067.7 5068.0 5086.3 5489.8 5493.5 5504.6 5524.1 5545.5 5263.4 5421.7 5423.2 5424.1 5465.3 5192.0 5201.3 5202.6 5203.7 5212.4 5662.2 5674.8 5679.5 5680.0 5680.7 5270.6 5286.7 5338.4 5339.6 5449.9 5395.4 5425.0 5435.3 5453.4 5490.8 5354.3 5367.6 5376.9 5394.6 5420.1 5211.7 5268.9 5330.1 5350.8 5352.7 4119.4 4121.6 4122.3 4125.3 4157.2 3680.0 3713.8 3716.4 3735.5 3736.0 5075.3 5098.6 5101.6 5140.9 5143.6 4927.3 5281.1 5403.7 5406.2 5427.4 4936.8 5031.2 5092.4 5097.6 5104.3 4480.1 4506.5 4509.9 4548.3 4560.0 5571.6 5595.0 5598.0 5619.9 5621.4 5249.1 5291.4 5330.6 5412.4 5433.5 5479.3 5482.6 5509.8 5513.3 5533.3 5245.4 5286.4 5299.7 5301.8 5344.9 5234.4 5303.7 5349.8 5369.0 5370.1 5245.5 5272.7 5274.4 5289.8 5314.8 5239.9 5290.3 5299.2 5311.9 5334.5 5007.0 5086.0 5149.8 5167.9 5233.6 5312.1 5327.6 5333.9 5350.9 5400.6 5078.8 5265.4 5285.4 5297.2 5341.1 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#1] ext.html Cycles:180 Average:54192.90 Median:19044.86 stddev:105724.87 (555.1%) stddev-sans-first:105944.81 10:39:53 INFO - PROCESS | 7356 | Values: 575.0 598.4 601.1 610.9 613.8 31978.5 43175.4 43656.8 43716.3 43987.8 44276.0 44709.3 44713.3 44782.1 44810.0 7766.4 8679.7 8825.0 8844.3 10168.0 9925.5 10283.1 10289.9 10301.3 10307.8 10344.4 10370.9 10375.8 10387.7 10389.4 7965.2 10222.8 10308.3 10451.5 10455.5 10292.4 10327.7 10332.0 10341.8 10347.1 40712.2 40891.4 40918.6 41056.0 41071.6 3483.4 3598.2 3653.7 3780.0 4229.7 31096.2 31201.4 31230.7 31550.2 32285.8 4252.9 4409.2 4409.5 4424.4 4580.5 319304.0 319888.0 320204.0 320937.0 321927.4 34208.3 35127.5 35148.0 37045.1 55349.4 35975.5 35986.5 36101.8 36115.3 36141.3 15298.2 15388.8 15394.6 15408.5 15432.1 13075.7 13109.5 13144.3 13146.3 13268.7 8373.4 8408.9 8451.3 8455.8 8496.9 3114.3 3230.7 3306.8 3325.7 3343.4 1061.7 1123.0 1134.9 1180.0 1223.8 31190.0 31416.4 31471.5 31745.4 31755.2 1121.1 1144.1 1184.4 1196.8 1230.7 16406.1 16682.4 16812.0 16839.7 17022.8 470514.0 471444.0 472128.0 474671.0 476586.0 37750.8 54858.0 55208.4 55406.0 55421.4 1368.3 1396.8 1408.7 1413.1 1419.3 29095.4 29392.1 30386.8 30503.4 30925.5 47861.5 48183.0 48206.8 48348.8 48538.0 387922.0 389507.0 390837.0 391021.0 392732.0 24227.5 26792.1 26995.1 28049.3 29335.4 45917.3 46407.8 46448.0 46806.1 46907.8 37642.6 38301.0 38598.0 38623.2 38711.6 39190.4 39498.4 39713.4 39820.6 40165.0 39459.6 39944.6 40150.0 40157.8 40497.8 32860.5 33463.5 33605.3 34271.3 34606.3 26681.7 26864.5 27161.6 27271.2 27602.0 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#2] jquery.html Cycles:180 Average:27279.68 Median:22600.10 stddev:78350.30 (346.7%) stddev-sans-first:78544.10 10:39:53 INFO - PROCESS | 7356 | Values: 399.2 404.3 406.3 409.3 412.1 13925.3 17023.5 17290.4 18805.3 18844.2 15029.0 17482.6 18188.0 18349.0 18938.0 5423.9 5629.1 5650.7 5655.7 5659.8 4030.6 5597.2 5616.3 5858.5 6244.9 6245.6 6282.4 6293.4 6307.3 6320.9 6004.8 6134.7 6185.8 6189.7 6198.4 5256.6 5431.4 5478.7 5485.1 5486.0 22424.3 22498.7 22701.5 22903.9 23157.0 2295.4 2494.9 2652.1 2668.2 2820.9 13321.9 14066.0 14131.9 14206.9 14678.8 1926.8 1970.8 1981.5 2020.4 2077.8 46936.0 57798.7 71417.6 71887.6 74207.0 10276.2 10478.8 10691.7 10691.9 10715.8 14670.0 14716.9 14738.6 14818.6 14861.9 8139.3 8151.2 8168.3 8257.1 8276.8 7073.9 7961.3 7970.2 7972.6 8074.1 6153.8 6462.7 6567.9 6630.0 6638.8 1952.2 1960.6 1961.2 1965.1 1985.7 921.7 1003.3 1090.8 1104.4 1120.7 18090.0 18090.8 18600.1 19187.7 19257.2 1013.3 1047.3 1051.6 1068.0 1117.4 10514.6 11070.3 11103.7 11180.1 11198.4 453974.0 477456.0 477577.0 477728.0 505559.0 74886.3 78455.0 78799.6 79357.6 79450.6 1056.9 1064.3 1067.6 1095.8 1101.0 16393.1 16502.5 16665.9 16816.4 17010.7 24932.9 25206.9 33440.5 33826.5 35667.1 47723.0 48065.8 49489.0 49690.5 50718.2 8448.2 8511.1 8680.8 8757.4 8868.2 12516.2 13008.7 13348.2 13424.8 13758.0 11219.3 11296.2 11406.9 11850.2 11927.2 11348.4 11460.3 11507.3 11905.2 11961.6 11501.6 11623.3 11761.0 11811.5 11974.5 7362.3 7658.7 8467.6 8472.0 8591.6 11266.8 11333.8 11528.7 11572.2 11800.6 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#3] mootools.html Cycles:180 Average:5639.15 Median:2685.41 stddev:3221.77 (120.0%) stddev-sans-first:3214.60 10:39:53 INFO - PROCESS | 7356 | Values: 1338.5 1499.4 1532.4 1713.6 1861.9 7983.4 8342.9 8378.4 8482.5 8548.7 8273.7 8408.5 8434.7 8452.6 8470.7 1902.3 2157.1 2225.4 2252.5 2275.5 2256.8 2260.8 2267.8 2274.5 2279.8 2214.3 2258.4 2263.6 2265.4 2269.5 1837.3 2204.1 2268.1 2275.2 2280.5 2222.7 2230.7 2238.8 2247.6 2274.5 8487.5 8589.5 8603.3 8612.5 8618.3 4959.6 5115.1 5133.8 5146.7 5163.1 3311.3 3324.4 3411.2 3427.4 3433.8 1092.8 1124.4 1130.6 1132.7 1137.5 11857.8 11882.8 11889.6 11908.2 11919.7 4394.6 4430.4 4506.6 4520.8 4531.9 9877.5 9937.3 9956.2 9976.8 9994.8 4672.6 5036.8 5108.1 5113.3 5168.7 4604.6 4688.8 4745.4 4748.3 4760.4 3844.5 3850.3 3898.3 3924.0 3983.4 1231.3 1231.5 1234.5 1237.3 1265.1 921.9 956.2 960.4 961.3 969.2 5622.1 5623.4 5631.4 5678.3 5798.9 448.0 463.5 464.2 465.7 483.4 3772.2 3851.4 3873.1 3878.5 3923.2 10226.8 10571.7 10607.7 10707.5 10722.5 10228.3 10502.0 10590.7 10599.7 10769.0 8008.0 8041.7 8081.8 8107.0 8209.3 5824.8 5898.3 5926.4 5933.2 5945.1 9558.4 9866.0 9935.3 9968.4 10006.1 9776.0 10084.2 10177.7 10355.0 10358.2 7297.7 7330.6 7358.2 7373.2 7387.3 8062.0 8077.2 8133.9 8146.7 8152.6 7594.2 7831.5 7905.7 7936.4 8046.2 7547.6 7585.1 7735.6 7750.9 7836.1 6579.7 6684.1 6724.3 6778.3 6842.5 4887.4 5038.0 5165.8 5209.5 5242.3 5219.6 5250.7 5276.1 5294.1 5319.8 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#4] prototype.html Cycles:180 Average:4510.45 Median:5150.16 stddev:1285.50 (25.0%) stddev-sans-first:1250.39 10:39:53 INFO - PROCESS | 7356 | Values: 338.8 341.6 346.7 347.6 348.5 5143.7 5177.6 5208.0 5232.6 5246.4 5091.3 5113.4 5128.9 5156.6 5173.2 3923.0 3929.5 4006.8 4033.6 4046.7 3998.7 4023.8 4024.5 4030.8 4081.3 3830.9 4057.5 4058.4 4064.3 4089.7 3978.6 4034.4 4038.1 4040.4 4090.6 3588.2 3612.6 3615.3 3632.5 3642.5 4965.5 5003.9 5012.7 5016.6 5017.2 4542.9 4590.8 4615.7 4619.4 4633.7 5238.1 5266.4 5336.2 5346.9 5366.7 3991.3 3996.0 3996.6 4006.8 4008.8 5313.8 5508.8 5513.3 5531.9 5548.9 3983.8 3986.9 4000.7 4030.2 4063.2 4057.7 4098.0 4098.4 4108.9 4115.7 4486.7 4583.0 4599.8 4604.3 4614.4 5347.9 5358.0 5365.3 5374.2 5398.1 1418.5 1439.9 1449.2 1450.5 1454.7 947.7 952.8 983.3 995.9 1003.7 4555.9 4782.8 5114.4 5161.8 5200.7 5489.8 5504.2 5504.5 5519.6 5570.1 4960.2 5101.3 5117.6 5128.9 5133.8 2840.9 2852.6 2878.7 2900.8 2908.8 5336.3 5506.4 5513.1 5516.6 5571.0 5363.8 5435.2 5442.9 5451.0 5494.6 5455.5 5515.7 5587.2 5593.6 5639.7 5227.0 5318.5 5394.2 5480.5 5483.3 5304.4 5321.7 5332.5 5350.6 5400.6 5481.5 5496.9 5556.4 5560.8 5590.4 3941.3 4016.4 4043.2 4047.6 4049.6 5333.7 5368.3 5373.3 5376.8 5379.6 5254.6 5259.9 5325.7 5332.5 5337.4 5068.9 5298.6 5395.6 5397.1 5404.5 5019.3 5110.2 5213.8 5214.4 5262.8 5394.9 5467.0 5483.2 5486.3 5492.3 5294.2 5302.5 5313.5 5319.9 5325.8 10:39:53 INFO - PROCESS | 7356 | 10:39:53 INFO - PROCESS | 7356 | [#5] yui.html Cycles:180 Average:14372.92 Median:25469.15 stddev:16351.64 (64.2%) stddev-sans-first:16365.57 10:39:53 INFO - PROCESS | 7356 | Values: 762.8 767.7 818.7 821.8 849.7 13626.7 14468.9 14501.3 14513.6 14532.1 12766.6 12865.7 13318.0 13338.7 13347.1 829.1 834.9 836.0 837.7 838.3 835.5 839.1 840.2 842.8 843.6 873.2 874.6 875.0 880.8 882.6 842.0 851.8 870.9 871.8 872.3 637.9 639.9 640.2 640.7 641.1 12228.9 12525.1 12565.8 12568.5 12635.1 11521.9 11735.6 11871.6 11922.3 12190.1 10240.2 10326.5 10353.5 10542.1 10598.5 3629.7 3686.2 3731.9 3877.6 3892.2 42087.8 42483.0 49135.8 49176.0 51713.5 8827.0 9864.7 10407.5 10907.5 11113.6 17713.5 17946.7 18594.2 18594.4 18668.7 8252.8 8362.1 8362.5 8544.9 8668.3 4625.8 4673.8 4696.9 4724.2 4837.1 3152.6 3257.6 3272.4 3286.1 3289.9 616.3 626.8 632.0 640.8 652.3 682.1 740.6 754.8 759.3 766.0 9448.4 9481.4 9499.0 9618.4 9764.2 615.0 663.4 757.0 762.9 768.8 4155.3 4498.7 4516.7 4535.0 4636.2 57329.4 58449.0 58801.4 59157.0 59384.0 43002.4 51581.0 53887.2 54277.5 54398.5 48597.3 52933.5 54112.5 54274.7 54584.0 9438.0 9468.8 9557.4 9692.4 9735.3 31828.7 33474.5 34899.7 34963.3 35209.3 41579.4 46312.5 46892.5 47004.8 47315.3 12115.8 12442.3 12534.2 12641.6 12886.7 13041.2 13133.5 13136.2 13254.8 13660.0 12955.8 13016.9 13044.2 13250.7 13520.3 12660.4 13015.7 13156.2 13311.7 13860.7 12335.3 12672.7 12838.9 13097.9 13562.2 9601.5 10354.4 10564.8 10577.8 10597.4 10636.8 10775.5 10828.5 10856.3 10999.2 10:39:53 INFO - PROCESS | 7356 | -------- Summary: end -------- 10:39:53 INFO - PROCESS | 7356 | 10:39:54 INFO - TEST-INFO | 7356: exit 0 10:39:54 INFO - TEST-OK | dromaeo_css | took 1110100ms 10:39:54 INFO - TEST-START | kraken 10:39:54 INFO - operating with platform_type : linux_ 10:39:54 INFO - Initialising browser for kraken test... 10:39:54 INFO - TEST-INFO | started process 7534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmdz5Ov/profile http://localhost:44184/getInfo.html) 10:39:57 INFO - PROCESS | 7534 | __metrics Screen width/height:1600/1200 10:39:57 INFO - PROCESS | 7534 | colorDepth:24 10:39:57 INFO - PROCESS | 7534 | Browser inner width/height: 1024/697 10:39:57 INFO - PROCESS | 7534 | __metrics 10:39:58 ERROR - PROCESS | 7534 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:39:59 INFO - TEST-INFO | 7534: exit 0 10:39:59 INFO - Browser initialized. 10:39:59 INFO - Running cycle 1/1 for kraken test... 10:39:59 INFO - TEST-INFO | started process 7614 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmdz5Ov/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:10 INFO - PROCESS | 7614 | RSS: Main: 150892544 10:40:10 INFO - PROCESS | 7614 | 10:40:13 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44184/tests/kraken/driver.html?testName=audio-beat-detection) 10:40:14 INFO - PROCESS | 7614 | RSS: Main: 207450112 10:40:14 INFO - PROCESS | 7614 | 10:40:16 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44184/tests/kraken/driver.html?testName=audio-dft) 10:40:17 INFO - PROCESS | 7614 | RSS: Main: 196292608 10:40:17 INFO - PROCESS | 7614 | 10:40:20 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44184/tests/kraken/driver.html?testName=audio-fft) 10:40:21 INFO - PROCESS | 7614 | RSS: Main: 263311360 10:40:21 INFO - PROCESS | 7614 | 10:40:23 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44184/tests/kraken/driver.html?testName=audio-oscillator) 10:40:23 INFO - PROCESS | 7614 | RSS: Main: 172527616 10:40:23 INFO - PROCESS | 7614 | 10:40:26 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44184/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:40:26 INFO - PROCESS | 7614 | RSS: Main: 177045504 10:40:26 INFO - PROCESS | 7614 | 10:40:29 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44184/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:30 INFO - PROCESS | 7614 | RSS: Main: 213245952 10:40:30 INFO - PROCESS | 7614 | 10:40:34 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44184/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:34 INFO - PROCESS | 7614 | RSS: Main: 210952192 10:40:34 INFO - PROCESS | 7614 | 10:40:37 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44184/tests/kraken/driver.html?testName=json-parse-financial) 10:40:38 INFO - PROCESS | 7614 | RSS: Main: 212029440 10:40:38 INFO - PROCESS | 7614 | 10:40:39 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44184/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:40 INFO - PROCESS | 7614 | RSS: Main: 200568832 10:40:40 INFO - PROCESS | 7614 | 10:40:42 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:42 INFO - PROCESS | 7614 | RSS: Main: 205193216 10:40:42 INFO - PROCESS | 7614 | 10:40:44 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:45 INFO - PROCESS | 7614 | RSS: Main: 213876736 10:40:45 INFO - PROCESS | 7614 | 10:40:48 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:48 INFO - PROCESS | 7614 | RSS: Main: 221724672 10:40:48 INFO - PROCESS | 7614 | 10:40:51 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:52 INFO - PROCESS | 7614 | RSS: Main: 217374720 10:40:52 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | Cycle 1(1): loaded http://localhost:44184/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44184/tests/kraken/driver.html?testName=ai-astar) 10:40:54 INFO - PROCESS | 7614 | RSS: Main: 261980160 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | __start_tp_report 10:40:54 INFO - PROCESS | 7614 | _x_x_mozilla_page_load 10:40:54 INFO - PROCESS | 7614 | _x_x_mozilla_page_load_details 10:40:54 INFO - PROCESS | 7614 | |i|pagename|runs| 10:40:54 INFO - PROCESS | 7614 | |0;ai-astar;115;109;112;111;112;114;112;113;113;113 10:40:54 INFO - PROCESS | 7614 | |1;audio-beat-detection;157;134;153;213;134;159;153;132;189;132 10:40:54 INFO - PROCESS | 7614 | |2;audio-dft;163;163;164;161;161;165;163;165;164;164 10:40:54 INFO - PROCESS | 7614 | |3;audio-fft;77;77;77;145;80;77;78;76;132;76 10:40:54 INFO - PROCESS | 7614 | |4;audio-oscillator;94;94;145;93;94;93;123;98;92;115 10:40:54 INFO - PROCESS | 7614 | |5;imaging-gaussian-blur;118;118;117;118;118;118;117;119;118;118 10:40:54 INFO - PROCESS | 7614 | |6;imaging-darkroom;148;148;148;148;147;148;148;148;150;148 10:40:54 INFO - PROCESS | 7614 | |7;imaging-desaturate;97;97;96;97;96;97;96;97;97;96 10:40:54 INFO - PROCESS | 7614 | |8;json-parse-financial;57;56;57;58;56;57;57;111;55;55 10:40:54 INFO - PROCESS | 7614 | |9;json-stringify-tinderbox;59;61;60;60;60;60;61;60;60;60 10:40:54 INFO - PROCESS | 7614 | |10;stanford-crypto-aes;85;83;82;88;83;83;84;84;86;84 10:40:54 INFO - PROCESS | 7614 | |11;stanford-crypto-ccm;142;134;135;148;133;125;136;131;195;133 10:40:54 INFO - PROCESS | 7614 | |12;stanford-crypto-pbkdf2;184;172;174;170;171;172;176;172;174;171 10:40:54 INFO - PROCESS | 7614 | |13;stanford-crypto-sha256-iterative;68;67;66;66;70;66;67;70;69;65 10:40:54 INFO - PROCESS | 7614 | __end_tp_report 10:40:54 INFO - PROCESS | 7614 | __start_cc_report 10:40:54 INFO - PROCESS | 7614 | _x_x_mozilla_cycle_collect,1712 10:40:54 INFO - PROCESS | 7614 | __end_cc_report 10:40:54 INFO - PROCESS | 7614 | __startTimestamp1462988454314__endTimestamp 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | ------- Summary: start ------- 10:40:54 INFO - PROCESS | 7614 | Number of tests: 14 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:1.65 (1.5%) stddev-sans-first:1.45 10:40:54 INFO - PROCESS | 7614 | Values: 115.0 109.0 112.0 111.0 112.0 114.0 112.0 113.0 113.0 113.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#1] audio-beat-detection Cycles:10 Average:155.60 Median:155.00 stddev:26.84 (17.3%) stddev-sans-first:28.47 10:40:54 INFO - PROCESS | 7614 | Values: 157.0 134.0 153.0 213.0 134.0 159.0 153.0 132.0 189.0 132.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#2] audio-dft Cycles:10 Average:163.30 Median:164.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 10:40:54 INFO - PROCESS | 7614 | Values: 163.0 163.0 164.0 161.0 161.0 165.0 163.0 165.0 164.0 164.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#3] audio-fft Cycles:10 Average:89.50 Median:77.00 stddev:26.03 (33.8%) stddev-sans-first:27.21 10:40:54 INFO - PROCESS | 7614 | Values: 77.0 77.0 77.0 145.0 80.0 77.0 78.0 76.0 132.0 76.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:93.50 stddev:17.90 (19.1%) stddev-sans-first:18.61 10:40:54 INFO - PROCESS | 7614 | Values: 94.0 94.0 145.0 93.0 94.0 93.0 123.0 98.0 92.0 115.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:40:54 INFO - PROCESS | 7614 | Values: 118.0 118.0 117.0 118.0 118.0 118.0 117.0 119.0 118.0 118.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 10:40:54 INFO - PROCESS | 7614 | Values: 148.0 148.0 148.0 148.0 147.0 148.0 148.0 148.0 150.0 148.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:97.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:40:54 INFO - PROCESS | 7614 | Values: 97.0 97.0 96.0 97.0 96.0 97.0 96.0 97.0 97.0 96.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:57.00 stddev:17.28 (30.3%) stddev-sans-first:18.24 10:40:54 INFO - PROCESS | 7614 | Values: 57.0 56.0 57.0 58.0 56.0 57.0 57.0 111.0 55.0 55.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 10:40:54 INFO - PROCESS | 7614 | Values: 59.0 61.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 60.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:1.75 (2.1%) stddev-sans-first:1.83 10:40:54 INFO - PROCESS | 7614 | Values: 85.0 83.0 82.0 88.0 83.0 83.0 84.0 84.0 86.0 84.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:135.50 stddev:19.89 (14.7%) stddev-sans-first:21.09 10:40:54 INFO - PROCESS | 7614 | Values: 142.0 134.0 135.0 148.0 133.0 125.0 136.0 131.0 195.0 133.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:173.00 stddev:4.06 (2.3%) stddev-sans-first:1.88 10:40:54 INFO - PROCESS | 7614 | Values: 184.0 172.0 174.0 170.0 171.0 172.0 176.0 172.0 174.0 171.0 10:40:54 INFO - PROCESS | 7614 | 10:40:54 INFO - PROCESS | 7614 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.78 (2.6%) stddev-sans-first:1.87 10:40:54 INFO - PROCESS | 7614 | Values: 68.0 67.0 66.0 66.0 70.0 66.0 67.0 70.0 69.0 65.0 10:40:54 INFO - PROCESS | 7614 | -------- Summary: end -------- 10:40:54 INFO - PROCESS | 7614 | 10:40:55 INFO - TEST-INFO | 7614: exit 0 10:40:55 INFO - TEST-OK | kraken | took 60766ms 10:40:55 INFO - SUITE-END | took 1170s 10:40:55 INFO - Completed test suite (00:19:31) 10:40:55 INFO - javascript benchmark 10:40:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2967.6438827340717, 3001.5197568389062, 3022.8639711212995, 3055.0834749575124, 3065.5095184770425, 4904.0, 4909.705417674942, 5067.315912351836, 5101.438841727411, 5107.565856902159, 4863.902721945559, 5131.3842338919385, 5132.307138536296, 5143.074246635604, 5211.192265198891, 4621.838235661752, 4644.5123262057505, 4683.672142949995, 4750.239961606144, 4799.232122860343, 4738.981119059402, 4761.47623761386, 4771.785269662873, 4804.6876952997945, 4820.228763397857, 4509.706869053501, 4633.930491042637, 4658.487566367697, 4678.953210467886, 4690.061987602493, 4642.210824159867, 4660.86017419478, 4670.673052886299, 4674.509176536482, 4696.201645720235, 4510.797014134372, 4566.041131362418, 4615.446146462413, 4640.048789998076, 4668.649851261135, 5012.548870601627, 5062.544371006627, 5067.695938243717, 5068.037072956164, 5086.287919691246, 5489.780408783644, 5493.533049690781, 5504.559635229172, 5524.116141417394, 5545.500904918576, 5263.421023687391, 5421.728913554307, 5423.186522021729, 5424.105022671294, 5465.344158700942, 5191.974040129775, 5201.297824793643, 5202.635815492879, 5203.661761985497, 5212.400573934008, 5662.178984047372, 5674.829755107354, 5679.545636349082, 5680.0, 5680.6591604503865, 5270.552003079782, 5286.656367336111, 5338.3593968724, 5339.626226164131, 5449.918251226235, 5395.433479484676, 5424.972875135599, 5435.320584926884, 5453.4273901240595, 5490.780368785284, 5354.330708661418, 5367.570594352443, 5376.892462150735, 5394.622376433612, 5420.132778755459, 5211.661242019257, 5268.92096618551, 5330.120530112566, 5350.839474815684, 5352.652077614943, 4119.423280740699, 4121.649659778893, 4122.2579935611275, 4125.319322311785, 4157.210130075276, 3680.024793429743, 3713.7586056906216, 3716.3682174030478, 3735.5143831301757, 3735.9813200934364, 5075.264086707481, 5098.617603679739, 5101.591872650179, 5140.922886156711, 5143.614228932845, 4927.260910863399, 5281.0758117329315, 5403.70279634616, 5406.162044883049, 5427.402985671573, 4936.753162341868, 5031.245313203049, 5092.388913330424, 5097.566706829961, 5104.259882317043, 4480.081583275421, 4506.504284528699, 4509.864704058883, 4548.2722764358205, 4559.954400456019, 5571.582131340166, 5595.0, 5598.0, 5619.943800562024, 5621.381648018714, 5249.055170069424, 5291.417944026231, 5330.573554115739, 5412.377576578665, 5433.483819037185, 5479.315085614298, 5482.643628164156, 5509.779608815763, 5513.3108361454815, 5533.280673512418, 5245.370555533361, 5286.444923283, 5299.735013249399, 5301.761420735999, 5344.946550534445, 5234.424213336606, 5303.708296043755, 5349.812756553502, 5369.033573956725, 5370.060239458157, 5245.501677340647, 5272.736363181749, 5274.4198138204, 5289.788408463618, 5314.813981510629, 5239.869003274948, 5290.25936368901, 5299.205119232146, 5311.920321195263, 5334.546563542142, 5006.998600279886, 5085.974570127126, 5149.819756308511, 5167.896642067213, 5233.633645644921, 5312.070387682063, 5327.600429967845, 5333.919991200058, 5350.919736204038, 5400.62195646301, 5078.822241221539, 5265.368155821173, 5285.418603953639, 5297.205419186998, 5341.092014357491], "value": 4995.307475384234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.9741261643228, 598.4015984015984, 601.0683440666966, 610.8869859076071, 613.840401495611, 31978.520322195185, 43175.35236971444, 43656.78171609138, 43716.34425483616, 43987.78006109973, 44276.0, 44709.32936005962, 44713.329300060526, 44782.104357912904, 44810.0, 7766.417518686121, 8679.73960781176, 8825.0, 8844.336674749387, 10168.0, 9925.454100024495, 10283.074523292902, 10289.89057243267, 10301.27891047627, 10307.793844123113, 10344.37933723979, 10370.9481452593, 10375.84436233457, 10387.688369348894, 10389.42858142802, 7965.203479652047, 10222.795544089076, 10308.278420510529, 10451.477426128664, 10455.529501172465, 10292.382457052527, 10327.690169294932, 10332.0, 10341.828216074633, 10347.068763811292, 40712.18575628471, 40891.386629200584, 40918.59081409207, 41056.0, 41071.58928410737, 3483.407820670492, 3598.24436868258, 3653.6711695947492, 3780.0360907968293, 4229.7462152270655, 31096.222594435094, 31201.375972480422, 31230.687693123004, 31550.211244718837, 32285.83857080723, 4252.893677658052, 4409.162259170779, 4409.536998615131, 4424.446944131983, 4580.541945805426, 319304.0, 319888.0, 320204.0, 320937.0, 321927.39036304905, 34208.315833683184, 35127.4730879037, 35148.0, 37045.073873153386, 55349.446505534426, 35975.4603680945, 35986.460203096976, 36101.819490902904, 36115.27769444596, 36141.277174456365, 15298.158601276815, 15388.769168462482, 15394.6151346215, 15408.537743867702, 15432.074075555503, 13075.738485230242, 13109.541166059142, 13144.27706476153, 13146.342682865818, 13268.734625307632, 8373.41386102979, 8408.873866892001, 8451.323894088458, 8455.788605284917, 8496.872546911802, 3114.3304189599344, 3230.6930841570015, 3306.7519936004896, 3325.7029758394247, 3343.4483310253536, 1061.7345663584103, 1122.9500417110028, 1134.9092072634169, 1180.032373453777, 1223.8409006829233, 31190.0, 31416.37167256642, 31471.52792708111, 31745.365092698015, 31755.20611984719, 1121.0807138146804, 1144.067584918289, 1184.4314728930162, 1196.7845787758288, 1230.655416483386, 16406.09766462856, 16682.416115435903, 16811.99128052321, 16839.663206735797, 17022.829771702123, 470514.0, 471444.0, 472128.0, 474671.0, 476586.0, 37750.811245943594, 54858.0, 55208.44791552114, 55405.99999999919, 55421.44578554243, 1368.2748143483968, 1396.818413606245, 1408.704172123845, 1413.1168019987713, 1419.332913530639, 29095.418091637624, 29392.11823645294, 30386.84806575953, 30503.389932201677, 30925.536116958712, 47861.52138478571, 48183.0, 48206.75896620494, 48348.75825620849, 48538.0, 387922.0, 389507.0, 390837.0, 391021.0, 392732.0, 24227.51544969126, 26792.062277820183, 26995.05517306885, 28049.298767530974, 29335.413291734472, 45917.31124033075, 46407.83980400382, 46448.0, 46806.06387872292, 46907.76546117248, 37642.623573763914, 38301.0, 38598.0, 38623.22753544857, 38711.6128838708, 39190.41214381844, 39498.40752388659, 39713.40429893612, 39820.601793982845, 40165.0, 39459.60540394559, 39944.60055399525, 40150.0, 40157.79921100376, 40497.79751101226, 32860.50709239411, 33463.49804752979, 33605.32789344151, 34271.314573708885, 34606.30787384289, 26681.733182667926, 26864.462710746066, 27161.59257611098, 27271.181864544094, 27602.0], "value": 18652.42201244411, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.1757021750086, 404.2581862282711, 406.31941497990863, 409.3450479233229, 412.126292260408, 13925.30373481327, 17023.489295321127, 17290.39483618072, 18805.341813036546, 18844.246230150795, 15029.0, 17482.562935926577, 18188.0, 18349.0, 18938.0, 5423.918641220384, 5629.099344104944, 5650.6892120933335, 5655.745491452874, 5659.801906933259, 4030.637242648161, 5597.244372009787, 5616.269884914956, 5858.502027327661, 6244.906326405107, 6245.594036387621, 6282.371762823704, 6293.4335909768115, 6307.337729538402, 6320.873582528347, 6004.759809607611, 6134.662593557353, 6185.783497577609, 6189.659568723719, 6198.44214020738, 5256.605754568384, 5431.402545719968, 5478.671279723229, 5485.122380419114, 5485.9725701371235, 22424.327270181922, 22498.66252006221, 22701.54596908069, 22903.88548057266, 23157.0, 2295.403195169268, 2494.912678056259, 2652.0982865825713, 2668.2262143978223, 2820.9280473420176, 13321.866781332159, 14066.0, 14131.858681413158, 14206.868969654635, 14678.779818302733, 1926.7976862429384, 1970.812772786583, 1981.5145289404045, 2020.3534868841975, 2077.7610574783944, 46936.0, 57798.71100644512, 71417.64291178563, 71887.64056179738, 74207.0, 10276.229282803819, 10478.842817357594, 10691.73270668224, 10691.893081069089, 10715.785684286426, 14670.0, 14716.926415368069, 14738.55784326472, 14818.555443336718, 14861.925690371696, 8139.348852091818, 8151.225633564802, 8168.305694015957, 8257.133001034925, 8276.793080172924, 7073.858522829514, 7961.323287520509, 7970.202979701983, 7972.561509117054, 8074.111847696749, 6153.753849845956, 6462.709178086903, 6567.934320656828, 6630.0, 6638.805015097232, 1952.194220346174, 1960.607878424321, 1961.1567026178595, 1965.0764140853776, 1985.6993669146732, 921.7326975177124, 1003.2926786615419, 1090.7763908398758, 1104.376027544435, 1120.6525976947173, 18090.0, 18090.768385840584, 18600.06999650039, 19187.712184317246, 19257.22971081169, 1013.3008224325192, 1047.2669131607904, 1051.6319288249204, 1067.9626213082504, 1117.402189828438, 10514.631987880388, 11070.28043177191, 11103.72240693989, 11180.10559155282, 11198.440077996067, 453974.0, 477456.0, 477577.0, 477728.0, 505559.0, 74886.25113748793, 78455.0, 78799.60600197077, 79357.60321198357, 79450.6027469859, 1056.8837427882927, 1064.270974382566, 1067.5836423794726, 1095.8465814785936, 1101.0200921180156, 16393.13177079947, 16502.504924852274, 16665.91667041657, 16816.411425600047, 17010.74483882718, 24932.875335623205, 25206.873965630053, 33440.49839252462, 33826.49260261049, 35667.10832229215, 47723.0, 48065.75967120142, 49489.0, 49690.50309496859, 50718.23922641089, 8448.155184481502, 8511.06378298399, 8680.78298042554, 8757.430767000125, 8868.201861832466, 12516.249025058525, 13008.699130087294, 13348.199108053548, 13424.798628020391, 13758.0, 11219.326840389604, 11296.209265351676, 11406.859314068526, 11850.170488065753, 11927.165098443025, 11348.375839328915, 11460.312381257152, 11507.252028618113, 11905.166638335233, 11961.64115076549, 11501.597444089417, 11623.302601843916, 11761.0, 11811.527538898348, 11974.461149248478, 7362.337389634744, 7658.655360508902, 8467.576621168793, 8472.0, 8591.613377397907, 11266.830997535208, 11333.829992550283, 11528.654140375802, 11572.18994670365, 11800.586979456022], "value": 9155.344202886025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.504753241301, 1499.445205274048, 1532.432999790078, 1713.5716070982255, 1861.8603604729649, 7983.441159118864, 8342.874856877144, 8378.413511054228, 8482.53346065966, 8548.70079547216, 8273.669053237878, 8408.453450525727, 8434.746957591267, 8452.619632116553, 8470.745877623682, 1902.258119333461, 2157.147926569004, 2225.354647152324, 2252.526969336441, 2275.544891021794, 2256.8307376946714, 2260.7513173550897, 2267.841251112423, 2274.4768703198274, 2279.783420575051, 2214.3356992902145, 2258.4128126687015, 2263.6265016272296, 2265.4336415896028, 2269.4553307206234, 1837.2742766607182, 2204.1403852497538, 2268.1267711931027, 2275.180934863448, 2280.4754906371627, 2222.655488399301, 2230.721159855018, 2238.7985081342595, 2247.5729611373786, 2274.4541310085624, 8487.490750554925, 8589.527575983318, 8603.268722158624, 8612.48325100496, 8618.310535157172, 4959.578435832958, 5115.104856650071, 5133.820316288912, 5146.742662866879, 5163.14808056674, 3311.2880730642787, 3324.434846076173, 3411.181316484051, 3427.365937301607, 3433.776804507699, 1092.7978324010085, 1124.3928278729477, 1130.5816847766296, 1132.7054965708892, 1137.5108703257597, 11857.762844743056, 11882.821757673642, 11889.583864564698, 11908.225965312227, 11919.702007449883, 4394.648428125743, 4430.3575981483145, 4506.59440650343, 4520.773961301922, 4531.864044078683, 9877.456739879231, 9937.254705897087, 9956.153726933291, 9976.750581235527, 9994.800103997879, 4672.556107169813, 5036.84889453317, 5108.131617625013, 5113.3097031900215, 5168.715720635401, 4604.58558729716, 4688.8124475021295, 4745.3593764842, 4748.311494833299, 4760.404949381327, 3844.5002149720917, 3850.2876967761053, 3898.2593307271527, 3923.9993801580413, 3983.42240375144, 1231.3043130631165, 1231.5012419969926, 1234.5247079874314, 1237.307108019512, 1265.1270623269913, 921.8525035994209, 956.1681337236648, 960.4333443268505, 961.274237950343, 969.1665167955553, 5622.100463925753, 5623.353314368818, 5631.436856314336, 5678.261825962599, 5798.9130163047585, 447.9910401791946, 463.4670129351206, 464.1621872520066, 465.6507619285536, 483.3836858006, 3772.2455508897783, 3851.441540976599, 3873.1479074604104, 3878.495795546561, 3923.156521347924, 10226.846597301046, 10571.682849514527, 10607.734806629742, 10707.464626768631, 10722.517486713117, 10228.335158214692, 10502.0, 10590.735231619272, 10599.682009539727, 10769.0, 8008.0, 8041.67833286662, 8081.838363232702, 8107.0, 8209.343252539782, 5824.796132135524, 5898.262717160355, 5926.377730338252, 5933.199018132497, 5945.10823376497, 9558.426494410358, 9866.0, 9935.304528682922, 9968.352057116264, 10006.09945104944, 9775.951120244354, 10084.193264538673, 10177.694669159937, 10355.0, 10358.171346292127, 7297.708091676273, 7330.633468326669, 7358.190599034209, 7373.15208750979, 7387.261273872569, 8062.0, 8077.15189905075, 8133.918660813316, 8146.674133034612, 8152.551609661526, 7594.164641889498, 7831.490953088031, 7905.723299684483, 7936.404769642185, 8046.195380462141, 7547.5848828314965, 7585.127710313384, 7735.613219338898, 7750.92249077502, 7836.138024817379, 6579.703913324008, 6684.097646817811, 6724.29394913527, 6778.288279730557, 6842.521023528305, 4887.437944636412, 5037.967216720637, 5165.845024649267, 5209.453007434165, 5242.318498595158, 5219.556337711337, 5250.684958902478, 5276.103062479311, 5294.100002999577, 5319.813806516753], "value": 4441.84345277226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.7764075710032, 341.62933217459056, 346.6550781971938, 347.56901442211665, 348.53818690235437, 5143.688359468335, 5177.5599074078655, 5207.973960130199, 5232.581393488521, 5246.37043554773, 5091.287219789232, 5113.411957624874, 5128.871778205537, 5156.639035267533, 5173.198154286082, 3922.960770392295, 3929.5481019682816, 4006.7996600169877, 4033.616806403387, 4046.676265898736, 3998.6801055915457, 4023.7786921719294, 4024.531046168142, 4030.8387664493384, 4081.3265811141127, 3830.885073447787, 4057.4928133983253, 4058.391241313797, 4064.288749468831, 4089.672826173899, 3978.6021397860127, 4034.4351790749024, 4038.1116154445976, 4040.4141399496907, 4090.550039495653, 3588.22853086585, 3612.584552776438, 3615.3311637347174, 3632.54593175853, 3642.508261384732, 4965.453800081988, 5003.9241563064115, 5012.674176178537, 5016.623753218487, 5017.172166592508, 4542.863714088583, 4590.806390338536, 4615.699979501355, 4619.445666520006, 4633.6988095774, 5238.05714971305, 5266.368035835686, 5336.199570064483, 5346.866328341823, 5366.6511676741275, 3991.2815693175216, 3995.98002009991, 3996.5603783583783, 4006.759594424344, 4008.7995600219874, 5313.814016509442, 5508.834734957958, 5513.338399392099, 5531.889362212733, 5548.889022219573, 3983.780892050907, 3986.86045988389, 4000.739951903117, 4030.214108248922, 4063.187362527506, 4057.736247143926, 4098.0, 4098.426220329156, 4108.91782164361, 4115.691323150776, 4486.685931984795, 4582.991741816795, 4599.816007359668, 4604.332371806069, 4614.446266448048, 5347.866303342447, 5357.973210133924, 5365.275687782178, 5374.1938709193155, 5398.055340315429, 1418.4964337660188, 1439.9352029158713, 1449.152245936118, 1450.4778279819257, 1454.6508837878832, 947.6590624266586, 952.8189643967625, 983.2674657380154, 995.9402435853872, 1003.7289931718395, 4555.931661025087, 4782.780391000273, 5114.363403710777, 5161.845144645667, 5200.7139607321205, 5489.835304940778, 5504.156107703884, 5504.4862662768055, 5519.641223320471, 5570.108782594765, 4960.206366981266, 5101.311322971352, 5117.616178786606, 5128.897422051538, 5133.820316288912, 2840.928976775597, 2852.6164810067103, 2878.747744731049, 2900.753435957925, 2908.7527560157623, 5336.332958380202, 5506.421825708323, 5513.062779327443, 5516.613837031449, 5571.025070612626, 5363.785448582091, 5435.22803370284, 5442.91835622466, 5450.972745136328, 5494.560435165177, 5455.454454554592, 5515.696636685021, 5587.189857470563, 5593.580481463825, 5639.746211420496, 5227.0, 5318.547923426398, 5394.163904594794, 5480.534154596904, 5483.341998960151, 5304.363476382704, 5321.680699158062, 5332.520073193431, 5350.571954243729, 5400.567954563547, 5481.5340696041285, 5496.945030549803, 5556.38879723222, 5560.833175004756, 5590.385057643737, 3941.3299739045005, 4016.4376987222395, 4043.1711499143194, 4047.57500462459, 4049.5747946466377, 5333.719907222161, 5368.269053008122, 5373.2746079278795, 5376.8118115865755, 5379.623426360274, 5254.605904557097, 5259.921101183409, 5325.653832500875, 5332.466753324637, 5337.386200586982, 5068.9239661405845, 5298.629095963245, 5395.622306438669, 5397.136458166675, 5404.486573775485, 5019.272205530251, 5110.207917772751, 5213.765380557808, 5214.366744794006, 5262.842114736564, 5394.947985143016, 5466.972665136649, 5483.177523371526, 5486.31421072366, 5492.258545096361, 5294.179402192666, 5302.469753024667, 5313.495217954288, 5319.920201197063, 5325.813596524103], "value": 4112.425930578149, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.7521055656913, 767.658392015553, 818.6929901287017, 821.8397412504561, 849.6728759427621, 13626.659333516667, 14468.92765536171, 14501.274936253185, 14513.5645930622, 14532.128072315669, 12766.553170639007, 12865.678358041054, 13318.0, 13338.66653333667, 13347.065705400626, 829.0548774397192, 834.929031032363, 835.9874601880977, 837.6523742646795, 838.2581415447312, 835.5195762436593, 839.1482645115213, 840.1514470384925, 842.8356470488258, 843.5866425451516, 873.1574031060026, 874.5714599846061, 874.9693760718376, 880.8150288439435, 882.6116508736136, 842.0274582856811, 851.7785375802274, 870.8954925408959, 871.7559083456642, 872.271653169601, 637.9489640828723, 639.8656282180748, 640.2157357237394, 640.6700549217157, 641.1120597971797, 12228.877711222864, 12525.123241373107, 12565.811512827315, 12568.497260109585, 12635.11554191207, 11521.942390288079, 11735.647930562096, 11871.643850684493, 11922.344271065089, 12190.085743569267, 10240.231982601335, 10326.483675816255, 10353.53409096585, 10542.10392116671, 10598.470076496222, 3629.7277704172293, 3686.189038411545, 3731.9067023324633, 3877.592852750477, 3892.2215556888455, 42087.789561052, 42483.0, 49135.75432122888, 49176.016479670565, 51713.482865171245, 8827.0, 9864.654737084167, 10407.53166107527, 10907.454627268606, 11113.611023614134, 17713.468595942144, 17946.730799038025, 18594.163262652943, 18594.442166735018, 18668.719969200472, 8252.8349433011, 8362.080171181287, 8362.498250105013, 8544.914550854537, 8668.306535477273, 4625.7687115644085, 4673.813047478131, 4696.8590942271785, 4724.196886529298, 4837.080954618611, 3152.6059242594674, 3257.5928008998876, 3272.3618894315855, 3286.0963235110535, 3289.851956661956, 616.3282022595392, 626.8119564130742, 632.0172132334172, 640.8077576726963, 652.2531701202097, 682.1336902134361, 740.6187460386341, 754.7767077688552, 759.3128218961738, 766.0, 9448.385854919408, 9481.383710058824, 9498.952505237568, 9618.422894626345, 9764.22627136298, 615.0497481391261, 663.3896814930275, 757.0, 762.881753328235, 768.8308572114126, 4155.252054630135, 4498.707584007095, 4516.706414083074, 4534.954650453519, 4636.235021221461, 57329.42670573241, 58449.0, 58801.41198588045, 59157.0, 59384.0, 43002.35496467556, 51581.0, 53887.19169212465, 54277.45722542803, 54398.45601544013, 48597.271040934414, 52933.47066529362, 54112.45887541153, 54274.728626357406, 54584.0, 9438.0, 9468.763280917756, 9557.415969011621, 9692.41845489273, 9735.269854761063, 31828.68171318257, 33474.497882531294, 34899.65100349065, 34963.300733984666, 35209.295814084086, 41579.37630935599, 46312.53687463082, 46892.53107468881, 47004.7649761749, 47315.290270646656, 12115.818262726243, 12442.315672638091, 12534.185277956904, 12641.620751377473, 12886.67783305425, 13041.217526948414, 13133.540326088541, 13136.211827290394, 13254.80117798253, 13660.0, 12955.805662914876, 13016.93491532574, 13044.217346959213, 13250.734985300047, 13520.25638589887, 12660.430280637578, 13015.739685206054, 13156.162744315687, 13311.733765324832, 13860.653483662585, 12335.321557314433, 12672.74654506923, 12838.871611284141, 13097.869021309665, 13562.186268823902, 9601.47191904452, 10354.430506322225, 10564.78931833053, 10577.788444231226, 10597.41714205726, 10636.84044739314, 10775.515101820594, 10828.512716927915, 10856.294340867818, 10999.175061870552], "value": 6677.139247128822, "unit": "score"}], "name": "dromaeo_css", "value": 6858.22408263586, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 112.0, 111.0, 112.0, 114.0, 112.0, 113.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 134.0, 153.0, 213.0, 134.0, 159.0, 153.0, 132.0, 189.0, 132.0], "value": 155.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 161.0, 161.0, 165.0, 163.0, 165.0, 164.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 145.0, 80.0, 77.0, 78.0, 76.0, 132.0, 76.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 145.0, 93.0, 94.0, 93.0, 123.0, 98.0, 92.0, 115.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 119.0, 118.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 148.0, 150.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 58.0, 56.0, 57.0, 57.0, 111.0, 55.0, 55.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 82.0, 88.0, 83.0, 83.0, 84.0, 84.0, 86.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 134.0, 135.0, 148.0, 133.0, 125.0, 136.0, 131.0, 195.0, 133.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 172.0, 174.0, 170.0, 171.0, 172.0, 176.0, 172.0, 174.0, 171.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 70.0, 66.0, 67.0, 70.0, 69.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1575.9, "alertThreshold": 2.0}]} 10:40:55 INFO - Return code: 0 10:40:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:40:55 ERROR - # TBPL SUCCESS # 10:40:55 INFO - Running post-action listener: _resource_record_post_action 10:40:55 INFO - ##### 10:40:55 INFO - ##### Finished run-tests step (success) 10:40:55 INFO - ##### 10:40:55 INFO - Running post-run listener: _resource_record_post_run 10:40:56 INFO - Total resource usage - Wall time: 1189s; CPU: 25.0%; Read bytes: 73728; Write bytes: 552894464; Read time: 268; Write time: 435548 10:40:56 INFO - install - Wall time: 17s; CPU: 27.0%; Read bytes: 4096; Write bytes: 261423104; Read time: 192; Write time: 231100 10:40:56 INFO - run-tests - Wall time: 1172s; CPU: 25.0%; Read bytes: 69632; Write bytes: 291471360; Read time: 76; Write time: 204448 10:40:56 INFO - Running post-run listener: _upload_blobber_files 10:40:56 INFO - Blob upload gear active. 10:40:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:40:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:40:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:40:57 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:40:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:40:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:57 INFO - (blobuploader) - INFO - Done attempting. 10:40:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:40:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:40:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:58 INFO - (blobuploader) - INFO - Done attempting. 10:40:58 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:58 INFO - Return code: 0 10:40:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08fdc2e251423030c00d5631af4a4af87283292bfa41e4badd3e401d2e473f5f337db62200dc7c3b0d483b99f012458183fada0f92d453b990ebb2102d98b207", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050379e70b43a49b820815badff592691253958261c3cf79d0ea1e302becad48435dd267175ff44c840e1b45f27d2464c8aaa2bf873f50442900aef1fbc2114d"} 10:40:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:58 INFO - Contents: 10:40:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08fdc2e251423030c00d5631af4a4af87283292bfa41e4badd3e401d2e473f5f337db62200dc7c3b0d483b99f012458183fada0f92d453b990ebb2102d98b207", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050379e70b43a49b820815badff592691253958261c3cf79d0ea1e302becad48435dd267175ff44c840e1b45f27d2464c8aaa2bf873f50442900aef1fbc2114d"} 10:40:58 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:58 INFO - Copying logs to upload dir... 10:40:58 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1224.979397 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-05-11 10:40:58.509039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 10:40:58.513048) ========= 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=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08fdc2e251423030c00d5631af4a4af87283292bfa41e4badd3e401d2e473f5f337db62200dc7c3b0d483b99f012458183fada0f92d453b990ebb2102d98b207", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050379e70b43a49b820815badff592691253958261c3cf79d0ea1e302becad48435dd267175ff44c840e1b45f27d2464c8aaa2bf873f50442900aef1fbc2114d"} build_url:https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005400 build_url: 'https://queue.taskcluster.net/v1/task/KhQKk_GzRS-RbsL3adoWOQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08fdc2e251423030c00d5631af4a4af87283292bfa41e4badd3e401d2e473f5f337db62200dc7c3b0d483b99f012458183fada0f92d453b990ebb2102d98b207", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/050379e70b43a49b820815badff592691253958261c3cf79d0ea1e302becad48435dd267175ff44c840e1b45f27d2464c8aaa2bf873f50442900aef1fbc2114d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 10:40:58.527205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:40:58.527554) ========= 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=0333a256a5e4f052382dd180000001b1-1462987231.759109-1059880021 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002131 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 10:40:58.609381) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 10:40:58.609722) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 10:40:58.610031) ========= ========= Total master_lag: 0.16 =========