builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-029 starttime: 1490903529.03 results: success (0) revision: 0156a91a9fbab5222c30a274e6d3477caf610074 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.027306) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.027745) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.028015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.051709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.051992) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-30 12:52:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2017-03-30 12:52:09 (37.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090173 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.249584) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.249865) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020267 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.339481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:09.339770) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0156a91a9fbab5222c30a274e6d3477caf610074 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 0156a91a9fbab5222c30a274e6d3477caf610074 --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=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-30 12:52:09,766 truncating revision to first 12 chars 2017-03-30 12:52:09,766 Setting DEBUG logging. 2017-03-30 12:52:09,766 attempt 1/10 2017-03-30 12:52:09,766 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0156a91a9fba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 12:52:10,088 unpacking tar archive at: mozilla-central-0156a91a9fba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.727565 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:10.081105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:10.081421) ========= script_repo_revision: 0156a91a9fbab5222c30a274e6d3477caf610074 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:10.081872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:10.082158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 12:52:10.091651) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-30 12:52:10.091937) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:52:10 INFO - MultiFileLogger online at 20170330 12:52:10 in /builds/slave/test 12:52:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:52:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:52:10 INFO - {'append_to_log': False, 12:52:10 INFO - 'base_work_dir': '/builds/slave/test', 12:52:10 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:52:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:52:10 INFO - 'branch': 'Firefox-Non-PGO', 12:52:10 INFO - 'buildbot_json_path': 'buildprops.json', 12:52:10 INFO - 'config_files': ('talos/linux_config.py',), 12:52:10 INFO - 'default_actions': ('clobber', 12:52:10 INFO - 'read-buildbot-config', 12:52:10 INFO - 'download-and-extract', 12:52:10 INFO - 'populate-webroot', 12:52:10 INFO - 'create-virtualenv', 12:52:10 INFO - 'install', 12:52:10 INFO - 'run-tests'), 12:52:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:52:10 INFO - 'download_minidump_stackwalk': True, 12:52:10 INFO - 'download_symbols': 'ondemand', 12:52:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:52:10 INFO - 'tooltool.py': '/tools/tooltool.py', 12:52:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:52:10 INFO - '/tools/misc-python/virtualenv.py')}, 12:52:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:52:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:52:10 INFO - 'gecko_profile': False, 12:52:10 INFO - 'gecko_profile_interval': 0, 12:52:10 INFO - 'installer_path': 'installer.exe', 12:52:10 INFO - 'log_level': 'info', 12:52:10 INFO - 'log_name': 'talos', 12:52:10 INFO - 'log_to_console': True, 12:52:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:52:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:52:10 INFO - 'opt_config_files': (), 12:52:10 INFO - 'pip_index': False, 12:52:10 INFO - 'suite': 'dromaeojs-e10s', 12:52:10 INFO - 'system_bits': '32', 12:52:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:52:10 INFO - 'title': 'talos-linux64-ix-029', 12:52:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:52:10 INFO - 'use_talos_json': True, 12:52:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:52:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:52:10 INFO - 'work_dir': 'build'} 12:52:10 INFO - [mozharness: 2017-03-30 19:52:10.620664Z] Running clobber step. 12:52:10 INFO - Running pre-action listener: _resource_record_pre_action 12:52:10 INFO - Running main action method: clobber 12:52:10 INFO - rmtree: /builds/slave/test/build 12:52:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:52:11 INFO - Running post-action listener: _resource_record_post_action 12:52:11 INFO - [mozharness: 2017-03-30 19:52:11.556929Z] Finished clobber step (success) 12:52:11 INFO - [mozharness: 2017-03-30 19:52:11.557027Z] Running read-buildbot-config step. 12:52:11 INFO - Running pre-action listener: _resource_record_pre_action 12:52:11 INFO - Running main action method: read_buildbot_config 12:52:11 INFO - Using buildbot properties: 12:52:11 INFO - { 12:52:11 INFO - "project": "", 12:52:11 INFO - "product": "firefox", 12:52:11 INFO - "who": "ryanvm@gmail.com", 12:52:11 INFO - "installer_path": "public/build/target.tar.bz2", 12:52:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:52:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:52:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:52:11 INFO - "stage_platform": "linux64", 12:52:11 INFO - "basedir": "/builds/slave/test", 12:52:11 INFO - "buildnumber": 780, 12:52:11 INFO - "platform": "ubuntu64_hw", 12:52:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:52:11 INFO - "slavebuilddir": "test", 12:52:11 INFO - "taskId": "J9bCeFnLRaSeMSiu2EpRpQ", 12:52:11 INFO - "branch": "mozilla-central", 12:52:11 INFO - "script_repo_revision": "production", 12:52:11 INFO - "revision": "0156a91a9fbab5222c30a274e6d3477caf610074", 12:52:11 INFO - "slavename": "talos-linux64-ix-029", 12:52:11 INFO - "repo_path": "mozilla-central" 12:52:11 INFO - } 12:52:11 INFO - Finding installer, test and symbols from parent task. 12:52:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9bCeFnLRaSeMSiu2EpRpQ'}, attempt #1 12:52:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9bCeFnLRaSeMSiu2EpRpQ'}, attempt #1 12:52:12 INFO - Task dependencies: PvPBgcrATKe9jbeDrVR4iQ 12:52:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ'}, attempt #1 12:52:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 12:52:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/test_packages.json 12:52:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.crashreporter-symbols.zip 12:52:12 INFO - Running post-action listener: _resource_record_post_action 12:52:12 INFO - [mozharness: 2017-03-30 19:52:12.963349Z] Finished read-buildbot-config step (success) 12:52:12 INFO - [mozharness: 2017-03-30 19:52:12.963566Z] Running download-and-extract step. 12:52:12 INFO - Running pre-action listener: _resource_record_pre_action 12:52:12 INFO - Running main action method: download_and_extract 12:52:12 INFO - mkdir: /builds/slave/test/build/tests 12:52:12 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:12 INFO - trying https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.test_packages.json 12:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:52:13 INFO - Downloaded 986 bytes. 12:52:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:52:13 INFO - Using the following test package requirements: 12:52:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:52:13 INFO - u'common': [u'target.common.tests.zip'], 12:52:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:52:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:52:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:52:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:52:13 INFO - u'mozbase': [u'target.common.tests.zip'], 12:52:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:52:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:52:13 INFO - u'web-platform': [u'target.common.tests.zip', 12:52:13 INFO - u'target.web-platform.tests.zip'], 12:52:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:52:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:52:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip 12:52:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:52:13 INFO - Fetch https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip into memory 12:52:15 INFO - Expected file size: 58226732 12:52:15 INFO - Obtained file size: 58226732 12:52:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:52:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip 12:52:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:52:25 INFO - Fetch https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.common.tests.zip into memory 12:52:27 INFO - Expected file size: 58226732 12:52:27 INFO - Obtained file size: 58226732 12:52:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.talos.tests.zip 12:52:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:52:38 INFO - Fetch https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.talos.tests.zip into memory 12:52:39 INFO - Expected file size: 14404152 12:52:39 INFO - Obtained file size: 14404152 12:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:39 INFO - trying https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 12:52:39 INFO - Downloading https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:52:42 INFO - Downloaded 62490006 bytes. 12:52:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 12:52:42 INFO - mkdir: /builds/slave/test/properties 12:52:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:52:42 INFO - Writing to file /builds/slave/test/properties/build_url 12:52:42 INFO - Contents: 12:52:42 INFO - build_url:https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 12:52:42 INFO - Running post-action listener: _resource_record_post_action 12:52:42 INFO - Running post-action listener: set_extra_try_arguments 12:52:42 INFO - [mozharness: 2017-03-30 19:52:42.466284Z] Finished download-and-extract step (success) 12:52:42 INFO - [mozharness: 2017-03-30 19:52:42.466489Z] Running populate-webroot step. 12:52:42 INFO - Running pre-action listener: _resource_record_pre_action 12:52:42 INFO - Running main action method: populate_webroot 12:52:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:52:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:52:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:52:42 INFO - '--tppagecycles', 12:52:42 INFO - '7'], 12:52:42 INFO - 'tests': ['tsvgm']}}, 12:52:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:52:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:52:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:52:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:52:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:52:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:52:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['damp', 'tps']}, 12:52:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'tests': ['damp', 'tps']}, 12:52:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['dromaeo_dom']}, 12:52:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:52:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['basic_compositor_video']}, 12:52:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:52:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'other-e10s': {'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['a11yr', 12:52:42 INFO - 'ts_paint', 12:52:42 INFO - 'tpaint', 12:52:42 INFO - 'sessionrestore', 12:52:42 INFO - 'sessionrestore_no_auto_restore', 12:52:42 INFO - 'tabpaint']}, 12:52:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['tsvgx', 12:52:42 INFO - 'tsvgr_opacity', 12:52:42 INFO - 'tart', 12:52:42 INFO - 'tscrollx', 12:52:42 INFO - 'cart', 12:52:42 INFO - 'tsvg_static']}, 12:52:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:52:42 INFO - 'tsvgr_opacity', 12:52:42 INFO - 'tart', 12:52:42 INFO - 'tscrollx', 12:52:42 INFO - 'cart', 12:52:42 INFO - 'tsvg_static']}, 12:52:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'talos_options': ['--disable-e10s'], 12:52:42 INFO - 'tests': ['tp5o']}, 12:52:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:52:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'talos_options': ['--disable-e10s', 12:52:42 INFO - '--xperf_path', 12:52:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:52:42 INFO - 'tests': ['tp5n']}, 12:52:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:52:42 INFO - 'talos_options': ['--xperf_path', 12:52:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:52:42 INFO - 'tests': ['tp5n']}}, 12:52:42 INFO - 'talos.zip': {'path': '', 12:52:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:52:42 INFO - Running post-action listener: _resource_record_post_action 12:52:42 INFO - [mozharness: 2017-03-30 19:52:42.486066Z] Finished populate-webroot step (success) 12:52:42 INFO - [mozharness: 2017-03-30 19:52:42.486155Z] Running create-virtualenv step. 12:52:42 INFO - Running pre-action listener: _resource_record_pre_action 12:52:42 INFO - Running main action method: create_virtualenv 12:52:42 INFO - Creating virtualenv /builds/slave/test/build/venv 12:52:42 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 12:52:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:52:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:52:42 INFO - Using real prefix '/usr' 12:52:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:52:43 INFO - Installing distribute.............................................................................................................................................................................................done. 12:52:45 INFO - Installing pip.................done. 12:52:45 INFO - Return code: 0 12:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:52:45 INFO - Reading from file tmpfile_stdout 12:52:45 INFO - Output received: 12:52:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:52:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:45 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:45 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 12:52:45 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 12:52:45 INFO - Using env: {'DISPLAY': ':0', 12:52:45 INFO - 'HOME': '/home/cltbld', 12:52:45 INFO - 'LANG': 'en_US.UTF-8', 12:52:45 INFO - 'LANGUAGE': 'en_US:en', 12:52:45 INFO - 'LOGNAME': 'cltbld', 12:52:45 INFO - 'MAIL': '/var/mail/cltbld', 12:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:45 INFO - 'MOZ_NO_REMOTE': '1', 12:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:45 INFO - 'NO_EM_RESTART': '1', 12:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:45 INFO - 'PWD': '/builds/slave/test', 12:52:45 INFO - 'SHELL': '/bin/bash', 12:52:45 INFO - 'SHLVL': '1', 12:52:45 INFO - 'TERM': 'linux', 12:52:45 INFO - 'TMOUT': '86400', 12:52:45 INFO - 'USER': 'cltbld', 12:52:45 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:45 INFO - '_': '/tools/buildbot/bin/python'} 12:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:45 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 12:52:45 INFO - Cleaning up... 12:52:45 INFO - Return code: 0 12:52:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:45 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:45 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>=3.1.1'] in /builds/slave/test/build 12:52:45 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>=3.1.1 12:52:45 INFO - Using env: {'DISPLAY': ':0', 12:52:45 INFO - 'HOME': '/home/cltbld', 12:52:45 INFO - 'LANG': 'en_US.UTF-8', 12:52:45 INFO - 'LANGUAGE': 'en_US:en', 12:52:45 INFO - 'LOGNAME': 'cltbld', 12:52:45 INFO - 'MAIL': '/var/mail/cltbld', 12:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:45 INFO - 'MOZ_NO_REMOTE': '1', 12:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:45 INFO - 'NO_EM_RESTART': '1', 12:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:45 INFO - 'PWD': '/builds/slave/test', 12:52:45 INFO - 'SHELL': '/bin/bash', 12:52:45 INFO - 'SHLVL': '1', 12:52:45 INFO - 'TERM': 'linux', 12:52:45 INFO - 'TMOUT': '86400', 12:52:45 INFO - 'USER': 'cltbld', 12:52:45 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:45 INFO - '_': '/tools/buildbot/bin/python'} 12:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:45 INFO - Downloading/unpacking psutil>=3.1.1 12:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:52:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:52:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:52:47 INFO - Installing collected packages: psutil 12:52:47 INFO - Running setup.py install for psutil 12:52:47 INFO - building 'psutil._psutil_linux' extension 12:52:47 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 12:52: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 12:52:48 INFO - building 'psutil._psutil_posix' extension 12:52: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 12:52: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 12:52:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:52:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:52:48 INFO - Successfully installed psutil 12:52:48 INFO - Cleaning up... 12:52:48 INFO - Return code: 0 12:52:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52: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.3'] in /builds/slave/test/build 12:52: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.3 12:52:48 INFO - Using env: {'DISPLAY': ':0', 12:52:48 INFO - 'HOME': '/home/cltbld', 12:52:48 INFO - 'LANG': 'en_US.UTF-8', 12:52:48 INFO - 'LANGUAGE': 'en_US:en', 12:52:48 INFO - 'LOGNAME': 'cltbld', 12:52:48 INFO - 'MAIL': '/var/mail/cltbld', 12:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:48 INFO - 'MOZ_NO_REMOTE': '1', 12:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:48 INFO - 'NO_EM_RESTART': '1', 12:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:48 INFO - 'PWD': '/builds/slave/test', 12:52:48 INFO - 'SHELL': '/bin/bash', 12:52:48 INFO - 'SHLVL': '1', 12:52:48 INFO - 'TERM': 'linux', 12:52:48 INFO - 'TMOUT': '86400', 12:52:48 INFO - 'USER': 'cltbld', 12:52:48 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:48 INFO - '_': '/tools/buildbot/bin/python'} 12:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:52:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:52:50 INFO - Installing collected packages: mozsystemmonitor 12:52:50 INFO - Running setup.py install for mozsystemmonitor 12:52:50 INFO - Successfully installed mozsystemmonitor 12:52:50 INFO - Cleaning up... 12:52:50 INFO - Return code: 0 12:52:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:50 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:50 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==2.5.1'] in /builds/slave/test/build 12:52:50 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==2.5.1 12:52:50 INFO - Using env: {'DISPLAY': ':0', 12:52:50 INFO - 'HOME': '/home/cltbld', 12:52:50 INFO - 'LANG': 'en_US.UTF-8', 12:52:50 INFO - 'LANGUAGE': 'en_US:en', 12:52:50 INFO - 'LOGNAME': 'cltbld', 12:52:50 INFO - 'MAIL': '/var/mail/cltbld', 12:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:50 INFO - 'MOZ_NO_REMOTE': '1', 12:52:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:50 INFO - 'NO_EM_RESTART': '1', 12:52:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:50 INFO - 'PWD': '/builds/slave/test', 12:52:50 INFO - 'SHELL': '/bin/bash', 12:52:50 INFO - 'SHLVL': '1', 12:52:50 INFO - 'TERM': 'linux', 12:52:50 INFO - 'TMOUT': '86400', 12:52:50 INFO - 'USER': 'cltbld', 12:52:50 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:50 INFO - '_': '/tools/buildbot/bin/python'} 12:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:52: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 12:52: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 12:52: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 12:52: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 12:52: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 12:52: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 12:52:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:52:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:52: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 12:52: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 12:52: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 12:52: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 12:52: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 12:52: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 12:52:53 INFO - Downloading functools32-3.2.3-2.tar.gz 12:52:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:52:53 INFO - warning: no files found matching '*.txt' 12:52:53 INFO - no previously-included directories found matching 'build' 12:52:53 INFO - no previously-included directories found matching 'dist' 12:52:53 INFO - no previously-included directories found matching '.git*' 12:52:53 INFO - Installing collected packages: jsonschema, functools32 12:52:53 INFO - Running setup.py install for functools32 12:52:53 INFO - warning: no files found matching '*.txt' 12:52:53 INFO - no previously-included directories found matching 'build' 12:52:53 INFO - no previously-included directories found matching 'dist' 12:52:53 INFO - no previously-included directories found matching '.git*' 12:52:53 INFO - Successfully installed jsonschema functools32 12:52:53 INFO - Cleaning up... 12:52:53 INFO - Return code: 0 12:52:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:53 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:53 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:52:53 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 functools32==3.2.3-2 12:52:53 INFO - Using env: {'DISPLAY': ':0', 12:52:53 INFO - 'HOME': '/home/cltbld', 12:52:53 INFO - 'LANG': 'en_US.UTF-8', 12:52:53 INFO - 'LANGUAGE': 'en_US:en', 12:52:53 INFO - 'LOGNAME': 'cltbld', 12:52:53 INFO - 'MAIL': '/var/mail/cltbld', 12:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:53 INFO - 'MOZ_NO_REMOTE': '1', 12:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:53 INFO - 'NO_EM_RESTART': '1', 12:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:53 INFO - 'PWD': '/builds/slave/test', 12:52:53 INFO - 'SHELL': '/bin/bash', 12:52:53 INFO - 'SHLVL': '1', 12:52:53 INFO - 'TERM': 'linux', 12:52:53 INFO - 'TMOUT': '86400', 12:52:53 INFO - 'USER': 'cltbld', 12:52:53 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:53 INFO - '_': '/tools/buildbot/bin/python'} 12:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:52:53 INFO - Cleaning up... 12:52:53 INFO - Return code: 0 12:52:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:53 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:53 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 12:52:53 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 12:52:53 INFO - Using env: {'DISPLAY': ':0', 12:52:53 INFO - 'HOME': '/home/cltbld', 12:52:53 INFO - 'LANG': 'en_US.UTF-8', 12:52:53 INFO - 'LANGUAGE': 'en_US:en', 12:52:53 INFO - 'LOGNAME': 'cltbld', 12:52:53 INFO - 'MAIL': '/var/mail/cltbld', 12:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:53 INFO - 'MOZ_NO_REMOTE': '1', 12:52:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:53 INFO - 'NO_EM_RESTART': '1', 12:52:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:53 INFO - 'PWD': '/builds/slave/test', 12:52:53 INFO - 'SHELL': '/bin/bash', 12:52:53 INFO - 'SHLVL': '1', 12:52:53 INFO - 'TERM': 'linux', 12:52:53 INFO - 'TMOUT': '86400', 12:52:53 INFO - 'USER': 'cltbld', 12:52:53 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:53 INFO - '_': '/tools/buildbot/bin/python'} 12:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:56 INFO - Downloading blobuploader-1.2.4.tar.gz 12:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:52:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:52:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:52:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:52:57 INFO - Downloading docopt-0.6.1.tar.gz 12:52:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:52:57 INFO - Installing collected packages: blobuploader, requests, docopt 12:52:57 INFO - Running setup.py install for blobuploader 12:52:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:52:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:52:57 INFO - Running setup.py install for requests 12:52:57 INFO - Running setup.py install for docopt 12:52:57 INFO - Successfully installed blobuploader requests docopt 12:52:57 INFO - Cleaning up... 12:52:57 INFO - Return code: 0 12:52:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:52:57 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:52:57 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 12:52:57 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 12:52:57 INFO - Using env: {'DISPLAY': ':0', 12:52:57 INFO - 'HOME': '/home/cltbld', 12:52:57 INFO - 'LANG': 'en_US.UTF-8', 12:52:57 INFO - 'LANGUAGE': 'en_US:en', 12:52:57 INFO - 'LOGNAME': 'cltbld', 12:52:57 INFO - 'MAIL': '/var/mail/cltbld', 12:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:52:57 INFO - 'MOZ_NO_REMOTE': '1', 12:52:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:52:57 INFO - 'NO_EM_RESTART': '1', 12:52:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:52:57 INFO - 'PWD': '/builds/slave/test', 12:52:57 INFO - 'SHELL': '/bin/bash', 12:52:57 INFO - 'SHLVL': '1', 12:52:57 INFO - 'TERM': 'linux', 12:52:57 INFO - 'TMOUT': '86400', 12:52:57 INFO - 'USER': 'cltbld', 12:52:57 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:52:57 INFO - '_': '/tools/buildbot/bin/python'} 12:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:52:57 INFO - Running setup.py (path:/tmp/pip-E_Sj_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:52:57 INFO - Running setup.py (path:/tmp/pip-116gME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:52:58 INFO - Running setup.py (path:/tmp/pip-6D0ZaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:52:58 INFO - Running setup.py (path:/tmp/pip-4Fa1zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:52:58 INFO - Running setup.py (path:/tmp/pip-rwykd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:52:58 INFO - Running setup.py (path:/tmp/pip-bNwXHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:52:58 INFO - Running setup.py (path:/tmp/pip-8zMWcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:52:58 INFO - Running setup.py (path:/tmp/pip-q_BhcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:52:58 INFO - Running setup.py (path:/tmp/pip-mNTYY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:52:58 INFO - Running setup.py (path:/tmp/pip-j7xFhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:52:58 INFO - Running setup.py (path:/tmp/pip-u00feT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:52:58 INFO - Running setup.py (path:/tmp/pip-65su2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:52:58 INFO - Running setup.py (path:/tmp/pip-ddGmn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:52:58 INFO - Running setup.py (path:/tmp/pip-zGvmgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:58 INFO - Running setup.py (path:/tmp/pip-gliQGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:52:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:52:58 INFO - Running setup.py (path:/tmp/pip-VWl3jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:52:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:52:59 INFO - Running setup.py (path:/tmp/pip-4WcEuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:52:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:52:59 INFO - Running setup.py install for manifestparser 12:52:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Running setup.py install for mozcrash 12:52:59 INFO - Running setup.py install for mozdebug 12:52:59 INFO - Running setup.py install for mozdevice 12:52:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Running setup.py install for mozfile 12:52:59 INFO - Running setup.py install for mozhttpd 12:52:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Running setup.py install for mozinfo 12:52:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Running setup.py install for mozInstall 12:52:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:52:59 INFO - Running setup.py install for mozleak 12:52:59 INFO - Running setup.py install for mozlog 12:53:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Running setup.py install for moznetwork 12:53:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Running setup.py install for mozprocess 12:53:00 INFO - Running setup.py install for mozprofile 12:53:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Running setup.py install for mozrunner 12:53:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Running setup.py install for mozscreenshot 12:53:00 INFO - Running setup.py install for moztest 12:53:00 INFO - Running setup.py install for mozversion 12:53:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:53:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:53:00 INFO - Cleaning up... 12:53:00 INFO - Return code: 0 12:53:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53:00 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:53:00 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 12:53:00 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 12:53:00 INFO - Using env: {'DISPLAY': ':0', 12:53:00 INFO - 'HOME': '/home/cltbld', 12:53:00 INFO - 'LANG': 'en_US.UTF-8', 12:53:00 INFO - 'LANGUAGE': 'en_US:en', 12:53:00 INFO - 'LOGNAME': 'cltbld', 12:53:00 INFO - 'MAIL': '/var/mail/cltbld', 12:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:00 INFO - 'MOZ_NO_REMOTE': '1', 12:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:00 INFO - 'NO_EM_RESTART': '1', 12:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:00 INFO - 'PWD': '/builds/slave/test', 12:53:00 INFO - 'SHELL': '/bin/bash', 12:53:00 INFO - 'SHLVL': '1', 12:53:00 INFO - 'TERM': 'linux', 12:53:00 INFO - 'TMOUT': '86400', 12:53:00 INFO - 'USER': 'cltbld', 12:53:00 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:00 INFO - '_': '/tools/buildbot/bin/python'} 12:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:53:01 INFO - Running setup.py (path:/tmp/pip-KxmtcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:53:01 INFO - Running setup.py (path:/tmp/pip-8kcXcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:53:01 INFO - Running setup.py (path:/tmp/pip-AlEIPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:53:01 INFO - Running setup.py (path:/tmp/pip-w1tcHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:53:01 INFO - Running setup.py (path:/tmp/pip-Jqzn2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:53:01 INFO - Running setup.py (path:/tmp/pip-v2WsZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:53:01 INFO - Running setup.py (path:/tmp/pip-zZbCDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:53:01 INFO - Running setup.py (path:/tmp/pip-HTqRkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:53:01 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:53:01 INFO - Running setup.py (path:/tmp/pip-H0q42L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:53:01 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:53:01 INFO - Running setup.py (path:/tmp/pip-5ufUws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:53:01 INFO - Running setup.py (path:/tmp/pip-jS1on1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:53:01 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)) 12:53:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:53:01 INFO - Running setup.py (path:/tmp/pip-wSaVrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:53:02 INFO - Running setup.py (path:/tmp/pip-a7mqlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:53:02 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)) 12:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:53:02 INFO - Running setup.py (path:/tmp/pip-8mXJXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 12:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:02 INFO - Running setup.py (path:/tmp/pip-c1I_Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:53:02 INFO - Running setup.py (path:/tmp/pip-hPWyGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:53:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:53:02 INFO - Running setup.py (path:/tmp/pip-i71guo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:53:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:53: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 12:53: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 12:53: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 12:53: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 12:53: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 12:53: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 12:53:04 INFO - Downloading blessings-1.6.tar.gz 12:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:53:04 INFO - Installing collected packages: blessings 12:53:04 INFO - Running setup.py install for blessings 12:53:04 INFO - Successfully installed blessings 12:53:04 INFO - Cleaning up... 12:53:04 INFO - Return code: 0 12:53:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:53:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:04 INFO - Reading from file tmpfile_stdout 12:53:04 INFO - Current package versions: 12:53:04 INFO - argparse == 1.2.1 12:53:04 INFO - blessings == 1.6 12:53:04 INFO - blobuploader == 1.2.4 12:53:04 INFO - docopt == 0.6.1 12:53:04 INFO - functools32 == 3.2.3-2 12:53:04 INFO - jsonschema == 2.5.1 12:53:04 INFO - manifestparser == 1.1 12:53:04 INFO - mozInstall == 1.12 12:53:04 INFO - mozcrash == 1.0 12:53:04 INFO - mozdebug == 0.1 12:53:04 INFO - mozdevice == 0.49 12:53:04 INFO - mozfile == 1.2 12:53:04 INFO - mozhttpd == 0.7 12:53:04 INFO - mozinfo == 0.9 12:53:04 INFO - mozleak == 0.1 12:53:04 INFO - mozlog == 3.4 12:53:04 INFO - moznetwork == 0.27 12:53:04 INFO - mozprocess == 0.25 12:53:04 INFO - mozprofile == 0.28 12:53:04 INFO - mozrunner == 6.13 12:53:04 INFO - mozscreenshot == 0.1 12:53:04 INFO - mozsystemmonitor == 0.3 12:53:04 INFO - moztest == 0.8 12:53:04 INFO - mozversion == 1.4 12:53:04 INFO - psutil == 3.1.1 12:53:04 INFO - requests == 1.2.3 12:53:04 INFO - wsgiref == 0.1.2 12:53:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53:04 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:53:04 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 12:53:04 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 12:53:04 INFO - Using env: {'DISPLAY': ':0', 12:53:04 INFO - 'HOME': '/home/cltbld', 12:53:04 INFO - 'LANG': 'en_US.UTF-8', 12:53:04 INFO - 'LANGUAGE': 'en_US:en', 12:53:04 INFO - 'LOGNAME': 'cltbld', 12:53:04 INFO - 'MAIL': '/var/mail/cltbld', 12:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:04 INFO - 'MOZ_NO_REMOTE': '1', 12:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:04 INFO - 'NO_EM_RESTART': '1', 12:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:04 INFO - 'PWD': '/builds/slave/test', 12:53:04 INFO - 'SHELL': '/bin/bash', 12:53:04 INFO - 'SHLVL': '1', 12:53:04 INFO - 'TERM': 'linux', 12:53:04 INFO - 'TMOUT': '86400', 12:53:04 INFO - 'USER': 'cltbld', 12:53:04 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:04 INFO - '_': '/tools/buildbot/bin/python'} 12:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:05 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)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:53:05 INFO - Cleaning up... 12:53:05 INFO - Return code: 0 12:53:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:53:05 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 0x20a91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2346b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, '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': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', '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 12:53:05 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 12:53:05 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 12:53:05 INFO - Using env: {'DISPLAY': ':0', 12:53:05 INFO - 'HOME': '/home/cltbld', 12:53:05 INFO - 'LANG': 'en_US.UTF-8', 12:53:05 INFO - 'LANGUAGE': 'en_US:en', 12:53:05 INFO - 'LOGNAME': 'cltbld', 12:53:05 INFO - 'MAIL': '/var/mail/cltbld', 12:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:05 INFO - 'MOZ_NO_REMOTE': '1', 12:53:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:05 INFO - 'NO_EM_RESTART': '1', 12:53:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:05 INFO - 'PWD': '/builds/slave/test', 12:53:05 INFO - 'SHELL': '/bin/bash', 12:53:05 INFO - 'SHLVL': '1', 12:53:05 INFO - 'TERM': 'linux', 12:53:05 INFO - 'TMOUT': '86400', 12:53:05 INFO - 'USER': 'cltbld', 12:53:05 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:05 INFO - '_': '/tools/buildbot/bin/python'} 12:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:53:05 INFO - Cleaning up... 12:53:05 INFO - Return code: 0 12:53:05 INFO - Running post-action listener: _resource_record_post_action 12:53:05 INFO - Running post-action listener: _start_resource_monitoring 12:53:05 INFO - Starting resource monitoring. 12:53:05 INFO - [mozharness: 2017-03-30 19:53:05.438356Z] Finished create-virtualenv step (success) 12:53:05 INFO - [mozharness: 2017-03-30 19:53:05.438823Z] Running install step. 12:53:05 INFO - Running pre-action listener: _resource_record_pre_action 12:53:05 INFO - Running main action method: install 12:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:05 INFO - Reading from file tmpfile_stdout 12:53:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:53:05 INFO - Reading from file tmpfile_stdout 12:53:05 INFO - Output received: 12:53:05 INFO - Usage: mozinstall [options] installer 12:53:05 INFO - Options: 12:53:05 INFO - -h, --help show this help message and exit 12:53:05 INFO - -d DEST, --destination=DEST 12:53:05 INFO - Directory to install application into. [default: 12:53:05 INFO - "/builds/slave/test"] 12:53:05 INFO - --app=APP Application being installed. [default: firefox] 12:53:05 INFO - mkdir: /builds/slave/test/build/application 12:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:53:21 INFO - Reading from file tmpfile_stdout 12:53:21 INFO - Output received: 12:53:21 INFO - /builds/slave/test/build/application/firefox/firefox 12:53:21 INFO - Running post-action listener: _resource_record_post_action 12:53:21 INFO - [mozharness: 2017-03-30 19:53:21.444322Z] Finished install step (success) 12:53:21 INFO - [mozharness: 2017-03-30 19:53:21.444600Z] Running run-tests step. 12:53:21 INFO - Running pre-action listener: _resource_record_pre_action 12:53:21 INFO - Running main action method: run_tests 12:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:53:21 INFO - Python 2.7.3 12:53:21 INFO - Return code: 0 12:53:21 INFO - grabbing minidump binary from tooltool 12:53:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:21 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 0x2344290>, '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 0x2344b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2345040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:53:21 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 12:53:21 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 12:53:21 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 12:53:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:53:21 INFO - Return code: 0 12:53:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:53:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:53:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:53:21 INFO - ENV: RUST_BACKTRACE is now 1 12:53:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:53:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:53:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:53:21 INFO - Using env: {'DISPLAY': ':0', 12:53:21 INFO - 'HOME': '/home/cltbld', 12:53:21 INFO - 'LANG': 'en_US.UTF-8', 12:53:21 INFO - 'LANGUAGE': 'en_US:en', 12:53:21 INFO - 'LOGNAME': 'cltbld', 12:53:21 INFO - 'MAIL': '/var/mail/cltbld', 12:53:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:53:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:21 INFO - 'MOZ_NO_REMOTE': '1', 12:53:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:53:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:53:21 INFO - 'NO_EM_RESTART': '1', 12:53:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:21 INFO - 'PWD': '/builds/slave/test', 12:53:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:53:21 INFO - 'RUST_BACKTRACE': '1', 12:53:21 INFO - 'SHELL': '/bin/bash', 12:53:21 INFO - 'SHLVL': '1', 12:53:21 INFO - 'TERM': 'linux', 12:53:21 INFO - 'TMOUT': '86400', 12:53:21 INFO - 'USER': 'cltbld', 12:53:21 INFO - 'XDG_SESSION_COOKIE': 'ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339', 12:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:21 INFO - '_': '/tools/buildbot/bin/python'} 12:53:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:53:21 INFO - mozversion application_buildid: 20170330191848 12:53:21 INFO - mozversion application_changeset: 0156a91a9fbab5222c30a274e6d3477caf610074 12:53:21 INFO - mozversion application_display_name: Nightly 12:53:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:53:21 INFO - mozversion application_name: Firefox 12:53:21 INFO - mozversion application_remotingname: firefox 12:53:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:53:21 INFO - mozversion application_vendor: Mozilla 12:53:21 INFO - mozversion application_version: 55.0a1 12:53:21 INFO - mozversion platform_buildid: 20170330191848 12:53:21 INFO - mozversion platform_changeset: 0156a91a9fbab5222c30a274e6d3477caf610074 12:53:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:53:21 INFO - mozversion platform_version: 55.0a1 12:53:21 INFO - using testdate: 1490903601 12:53:21 INFO - actual date: 1490903601 12:53:21 INFO - starting webserver on 'localhost:47359' 12:53:21 INFO - SUITE-START | Running 2 tests 12:53:21 INFO - TEST-START | dromaeo_css 12:53:21 INFO - operating with platform_type : linux_ 12:53:21 INFO - Initialising browser for dromaeo_css test... 12:53:21 INFO - TEST-INFO | started process 6747 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1MDpFu/profile http://localhost:47359/getInfo.html) 12:53:26 INFO - PID 6747 | __metrics Screen width/height:1600/1200 12:53:26 INFO - PID 6747 | colorDepth:24 12:53:26 INFO - PID 6747 | Browser inner width/height: 1024/697 12:53:26 INFO - PID 6747 | __metrics 12:53:27 ERROR - PID 6747 | 1490903607502 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6398:36 < canClose@browser.js:5243:12 < forceQuit@jar:file:///tmp/tmp1MDpFu/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmp1MDpFu/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmp1MDpFu/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmp1MDpFu/profile/extensions/talos-powers@TalosPowersService.js:55:9 12:53:27 ERROR - PID 6747 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 12:53:28 INFO - TEST-INFO | 6747: exit 0 12:53:28 INFO - Browser initialized. 12:53:28 INFO - Running cycle 1/1 for dromaeo_css test... 12:53:28 INFO - TEST-INFO | started process 6881 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1MDpFu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:53:40 INFO - PID 6881 | RSS: Main: 155615232 12:53:40 INFO - PID 6881 | 12:56:46 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47359/tests/dromaeo/cssquery-ext.html) 12:56:47 INFO - PID 6881 | RSS: Main: 164802560 12:56:47 INFO - PID 6881 | 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | A coding exception was thrown and uncaught in a Task. 12:58:29 INFO - PID 6881 | 12:58:29 ERROR - PID 6881 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:58:29 INFO - PID 6881 | Full stack: 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | A coding exception was thrown and uncaught in a Task. 12:58:29 INFO - PID 6881 | 12:58:29 ERROR - PID 6881 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:58:29 INFO - PID 6881 | Full stack: 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | A coding exception was thrown and uncaught in a Task. 12:58:29 INFO - PID 6881 | 12:58:29 ERROR - PID 6881 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:58:29 INFO - PID 6881 | Full stack: 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | ************************* 12:58:29 INFO - PID 6881 | A coding exception was thrown and uncaught in a Task. 12:58:29 INFO - PID 6881 | 12:58:29 ERROR - PID 6881 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:58:29 INFO - PID 6881 | Full stack: 12:58:29 INFO - PID 6881 | ************************* 12:59:49 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-ext.html (next: http://localhost:47359/tests/dromaeo/cssquery-jquery.html) 12:59:50 INFO - PID 6881 | RSS: Main: 161062912 12:59:50 INFO - PID 6881 | 13:02:52 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47359/tests/dromaeo/cssquery-mootools.html) 13:02:53 INFO - PID 6881 | RSS: Main: 168562688 13:02:53 INFO - PID 6881 | 13:05:55 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47359/tests/dromaeo/cssquery-prototype.html) 13:05:55 INFO - PID 6881 | RSS: Main: 160829440 13:05:55 INFO - PID 6881 | 13:08:58 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47359/tests/dromaeo/cssquery-yui.html) 13:08:59 INFO - PID 6881 | RSS: Main: 168751104 13:08:59 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | Cycle 1(1): loaded http://localhost:47359/tests/dromaeo/cssquery-yui.html (next: http://localhost:47359/tests/dromaeo/cssquery-dojo.html) 13:12:02 INFO - PID 6881 | RSS: Main: 162836480 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | __start_tp_report 13:12:02 INFO - PID 6881 | _x_x_mozilla_page_load 13:12:02 INFO - PID 6881 | _x_x_mozilla_page_load_details 13:12:02 INFO - PID 6881 | |i|pagename|runs| 13:12:02 INFO - PID 6881 | |0;dojo.html;2725.2641786717586;2737.931551711208;2750.2711781377925;2779.7637200837917;2780.332720147164;5175;5176.068307704616;5239;5245.186996015615;5250.527452529272;5216.8695782605355;5228.111221092414;5248.8425347239545;5322.547583455411;5359.99999999999;4836.51634836517;4870.366852309213;4888.291197776321;4894.192458244385;4931.679440836352;4860.1008813369635;4900.926486102711;4949.331840201563;4963.627727920402;5007.474215207405;4748.169070412664;4800.447948485934;4808.326834243174;4860.951390486085;4875.926861097087;4808.687435316729;4830.758462076882;4840.975795121037;4865.732384718839;4889.095517329305;4711.246200607886;4744.833930812405;4787.13831510328;4800.687955282895;4817.855464336076;5103.56619687327;5177.922331165036;5201.791928322863;5242.9213561796605;5273.235380869753;5665.150227465913;5675;5730.541556675456;5742.511886489653;5759.856003599902;5715.6284841485585;5718.857028574319;5728.570357223225;5733.168690539848;5734.8279551613105;5184.326037615086;5307.177387504981;5318.654287471302;5323.1216849219445;5371.785128594813;5644;5703.62926409782;5709.8287051388525;5718.771249150071;5720.1991721159075;5461.208124821878;5546.195801608825;5576.804811831566;5605.80379686709;5607.186957891083;5648.237487939158;5700.885982280414;5728.971355143198;5729.8567535811935;5735.483806457355;5622.690752008597;5676.744546495418;5677.290338707661;5687.829365119053;5692.6299790513485;5459.044667183227;5554.527865131428;5594.608377413623;5643.858903527444;5661.207430959669;4752.215884379108;4752.95247047532;4767.6662633615315;4800.591949684246;4806.639502037361;4073.165001174752;4075.0219947212454;4080.6531444827524;4087.1212689271947;4129.607687269705;4677.134730074947;4794.161021821167;4851.80592776285;4895.6817806843155;4902.117618828645;5679.091345384719;5707.143928410772;5712;5726.255586773693;5755.712214389264;4810.759462026885;4838.612910967114;4840.515948405202;4849.612031037508;4889.951100489021;4911.263310503382;5000.924986125211;5029.924551131663;5040.143175660147;5058.696478211319;5284.180951952299;5297.231901374202;5308.787648494172;5334.893302133858;5358.356997160366;5543.556515478671;5603.971980140073;5644.096944488862;5665;5668.659880407189;5219.243209734643;5253.711045892513;5310.548603368757;5332.173513105475;5338.626296159232;5392.568594512508;5479.000329759218;5554;5554.333479982428;5621.213030175697;5703.287089113861;5712.143178523254;5736.885262294814;5771.624844385102;5786.855328616818;5445.972770136124;5501.724913754377;5503.807366742145;5512.090505066619;5524.33707955048;5382.058139825436;5428.728563571726;5504.862378440411;5510.228568000403;5545.694986775766;5351.759170837399;5444.700541470257;5476;5477.890442191054;5492.588055895744;5569.777208911761;5581.776728930798;5581.967336042845;5584.218209450762;5585.5531557476115;5286.603504737083;5409.0804563223555;5455.9454405455435;5462.3445186577865;5495.53287970527 13:12:02 INFO - PID 6881 | |1;ext.html;669.3273260373327;714.8606021825743;747.1072068877692;1071.5927947379994;1093.1582681335376;33610.327793444114;34495.31009379811;34558.827205863934;34611.30777384457;34711.30577388451;36250.81874590623;36501.45247821285;36546.086347841316;36598;36679.44980825283;7246.420286377091;7336.633168341588;7377.262273772633;7580.05249343832;7607.847843043136;7617.504862183968;7673.079230492349;7729.072511298652;7750.806229844243;7751.6124193790365;7686.500377475474;7749.418793590475;7758.883616745753;7765.689372425096;7779.649915753776;7666.578338191397;7700.499467534621;7710.845783084335;7715.807104822368;7727.613619319011;7461.216572259888;7466.4773465857415;7467.8506429871095;7491.962540187319;7501.3623841973495;34463.48304775431;34670.306593868234;34871.651283487095;34886.82556587226;34896.12759681003;2561.6029515424952;2562.2627878091985;2587;2626.054620336677;2643.070558492311;24045.87977060121;24086.518269634686;24513.877430612913;24671.56690009783;25100.99596016157;2463.322586288767;2479.2438306316567;2484.229888734498;2509.1092662105057;2532.7973762098986;127604.36197819044;130439;142659;144330;145246.2737686305;42211.7889410551;42384.516541920886;42432.36351454731;42531.93670158271;42598;43700.125997479874;43779.78110109429;43803;43817.34273985893;43820;11563;11597.594084207181;11663.650090497298;11686.12354073448;11717.12121590884;9229.077092290718;9331.20684741791;9381;9409.388389754644;9436.811263774685;13147.211167329991;13161.670958226121;13356.332183390792;13364.599062028155;13466.797998030037;5422.43064478232;5524.917126243109;5579.721013949286;5648.378678345379;5714.9142762857755;867.5185272173878;896.3232759266712;896.4038914122166;906.0395980260945;926.1109335038304;20590.897045514976;21515.78484215169;21566;21615.001224773467;21919.452013699785;909.6497848328441;978.5400861595039;982.9459379734184;989.8911119776819;1011.5346940407347;11594.821807203263;11662.825057624143;11702.824457633142;11747.236429632047;11766.411679415995;239625;240511;242609;242891;242983;43446.78276608597;43758.12483750371;43788.12423751507;43832;44021.77989110034;1221.780079585683;1287.9162854414433;1292.0309767674244;1293.1142167615026;1298.733759579284;20117.497062572966;20261.79738202599;21075.473113172295;21255.681164782232;21616.67574986408;31253.68746312508;31418.21454463657;31477;31544;31549.21126971752;244620.99205535438;244960;245155;245226;245294;29555;30632;31159.84420077885;31198.37603247968;31246.68753312438;37193.256134877694;37448.62551374451;38151.236975260894;38178.2364352717;38653.613463865004;28294.71705283003;28391.574126388536;28564.857175713987;28722.71277287284;29354.70645293605;28261.57607635928;28301;28624.42751145007;29451.41097178087;29612.259693507836;30925.381492370478;31346.686533134376;31495.52756708697;31918.88283910052;31973.840130800127;27536.862315689094;28099.157025289274;28133;28273.858630706716;28493.145205643865;20704.48238794042;22579.887100564392;22672.206472773374;22851.31446056621;22886.97008634582 13:12:02 INFO - PID 6881 | |2;jquery.html;411.76323613922;421.2004212004211;431.8488529014845;437.61927123402626;439.5494618016533;17678.999999999985;18283.908580457097;18848.62302753944;18961.336353227623;19205.42383728487;16414;16642.58393540162;17014.149292535385;17123.400680976167;17477.388291409803;5550.361708403525;5677.17680936263;5783.566232532556;5825.097109947965;5856.063029915214;5757.395473475288;5787;5826.067829147316;5832.154337621042;5835.474807267345;5532.28080349554;5774.855628609297;5782.797602083928;5792.507636850873;5802.651840889539;5689.374168841424;5749.166370876221;5781.62419442737;5799.652020878761;5801.564882633798;5165.793368265265;5205.24523944026;5239.79040838366;5240.79036838526;5251.789928402859;22537.211197608005;22886.198983035512;22939.42651433711;23033.654495182585;23037.884810576008;1484.5620541940045;1550.434091556585;1565.24085818378;1566.4360830101155;1619.4898606938777;13896.861031389657;14502;14639.268036598127;14849.70300593982;15318.387264509407;1635.7628143919185;1646.1275523972313;1659.2616285752845;1668.2659629762866;1673.171779968911;88521;88754.5562272191;88771;90261.54869225612;92788;11084.556617735361;11313.321200727984;12080.275183489019;12132;12234.388280585936;15649.687006259812;15665.138417387154;15704.842951570337;15753;15832.445864394922;7052.541584797074;7239.20368759436;7271.927280727231;7443.590602516913;7531.661075251627;6508.837279067967;6590.275069742324;6725.2602213756445;6762.69567869447;6796.592204467649;7009.61447120403;7064.682089305994;7202.927970720226;7345.595992220372;7377.741779037708;2057.3005178239173;2103.516191276015;2113.778053304381;2115.513431910669;2217.800397964191;812.8333691593209;850.7405241401432;878.7715194049593;879.7536689726886;888.3825741109996;16899.23953422099;17040.829591703925;17486.21312040961;17591;17891;843.1484200957077;864.4553931023415;868.3660927522943;889.3774357949461;894.1594900793234;9241.486530929498;9827.901720982842;9844.80310393788;9856.31005829585;9870.605175792887;463540;464439;464811;468679;475282;87833;87848;88263;91654;91782.54108729414;998.7203582996773;1028.4446398944635;1052.9838705648933;1054.6361505280665;1058.9682309530726;17148.228329725443;17916.462506124837;18439.90780046091;18604.25582976666;18724.6255074895;42300.096495657155;42377.36433953555;42856.78571607122;43053.56946430496;43674.34488482739;54022.72988635032;54528.45471545234;54786.45213547813;55586;56779.71610142088;8130.4308698392915;8202.548859812767;8468.57657117154;8640.956795215983;8652.178043085896;13172.60482185536;13312.400941957829;13359.799603005767;13546.05177637556;13744.450221991008;10910.945445272722;10995.175361848052;11243.49404276826;11414.714632134263;11935.343556104497;10455.424951627734;10749.408782517037;10966.232363734462;11038;11319.660410187707;10874.293170943864;10903.509342079467;10970.890291096986;11359.48882300282;11473.483693233991;8445.40882138269;8458.450200736932;8524.74425767228;8561.315094792526;8815.206631403205;11273.309003649518;11682.182247242612;11844.763104737685;11932.522699092286;12335.321557314075 13:12:02 INFO - PID 6881 | |3;mootools.html;1240.615409223141;1325.1850112181005;1331.9933400333;1333.493272556429;1343.100122917645;9567.378120422176;9923;9929.40423574586;9962.701118966424;10195.54120064597;9970.202383809277;10150.187984961203;10169.644062457815;10176.135028522585;10185.694429167137;2143.4962783745846;2175.7715439878743;2193.396815875631;2193.56128774245;2195.9780402195934;2144.3138195777356;2175.3473957812594;2184.1045171479695;2197.4286685461734;2206.889655517226;2110.8627939183903;2171.2183613898983;2182.4434769133927;2190.3866917263113;2199.802017818396;2144.6997420361163;2149.258505815499;2151.333086743107;2156.2884248198056;2156.54712510373;1796.7035439152523;1866.337450205171;2031.6647753120717;2073.6785798201304;2086.6869969504546;9875.061869122423;9960.750981225456;9966.103050725469;9972.301938864282;10009.199264058858;5091.439941606421;5284.9735751320995;5294.258803767476;5305.204219367087;5315.920261196085;3158.7630927680284;3205.1346136543;3277.016894931511;3343.765936384454;3376.7467439941856;971.5627967414621;998.7353351361895;998.8202123617746;1004.829179039565;1007.5113569918584;13997.30013499321;14037.490627343164;14037.98183390576;14222.288885555681;14241.572752817328;4461.933071003938;4660.6970546914345;4683.484816670163;4707.741074240949;4738.905221895543;10675.306105103144;10691.144708423308;10703.197260205516;10703.732406689895;10716.35701857891;5336.599755018389;5366.946330536723;5382.2464854920345;5391.353037635509;5393.298871146726;4675.929861052087;4852.441969173519;4889.144399730033;4951.554360107608;4963.776630051656;3690.944635830465;3777.490038844776;3808.771473711586;3853.7687738735767;3854.6530812226474;1154.2382027861738;1158.3455347728507;1161.6631176958588;1177.0465922602718;1178.7701398227264;691.9930800691928;699.6641612026154;721.3868212019742;730.8428687832036;742.3764038207931;5273.156294992783;5299.019681358961;5381.650192737459;5482.862928426821;5530.198121272473;551.2640624765983;551.9834404967858;553.297312413241;557.637535601862;561.0770282884632;3669.7064234861145;3928.9214215715524;3990.182012687392;4040.1313717550865;4075.9796201018803;11483.13876459269;11902.107341949388;12074.818877716842;12655.62033138989;12900.35498225085;12334.136610437363;12770.297633630054;12832.80750788739;12899.290539020443;12936.67658308531;9058.728238152999;9269.490178040272;9425.481598512011;9469.289803264783;9490.050994900455;6166.475849552839;6306.779262725782;6352.841178970562;6373.74505019794;6476.5466417350335;11231.213815033196;11591;11597.88402115968;11706.297622142698;11754.647360579196;11589.826152607886;11702.88297117018;11714.472848721998;11731.648050558497;11904.404779760804;8217.260446559838;8343.499390036617;8354.331653467587;8407.411481196257;8425.946756655418;8943.552822358986;8979.775505612412;9096;9137.862932056158;9143.085691430804;7673.577953212627;7685.807854803451;7690.692372305045;7695.730649427243;7742.65158067877;7376.520526165782;7401.296876796696;7438.144613369532;7627.54234745917;7658.349040331635;6939.340762627542;6959.930400695928;6990.790276291719;7031.718731250693;7034.824129396806;5212.947870521398;5282.47175282484;5315.0964336063735;5351.518363347317;5470.452954704498;5227.634065615371;5354.571634269327;5379.623426360117;5454.318210223722;5495.780168793363 13:12:02 INFO - PID 6881 | |4;prototype.html;372.9123655940853;385.70300868331395;387.1559999201741;393.3902451200642;398.4859531204748;5509.449055094488;5525.309336332958;5552.194931734901;5561.304836895388;5660.377358490563;5422.240886275915;5496.780128794843;5504.825593890411;5512.283403157594;5548.86127846804;4172.958270417303;4290.377895205193;4308.655307575386;4321.762303073329;4328.761918094519;4286.614204721574;4302.139572085595;4302.26861433557;4311.094670119279;4329.502107257674;4269.466316710411;4275.380069889864;4289.313709806432;4295.828166873322;4315.482142142947;4149.585041495857;4254.361845723135;4263.317869140923;4268.42376279203;4308.82764689412;3675.2465744522574;3722.7766334020043;3730.235301763132;3737;3739.9065023374633;5428.158635411518;5436.130219164954;5439.972800136014;5440.211169380457;5441.374241962186;4710.764461776932;4842.515748425165;4858.708477491362;4871.14754917888;4879.341288925985;5817.301923769132;5834.912476312858;5838.182654428383;5841.620294680833;5843.415658434165;4043.413705012786;4044.564179716188;4045.1909618076206;4053.7365071270274;4054.878353649395;6044;6141.938580614182;6172.611162488449;6196.132541444202;6225.782097626607;4152.397902304149;4157.397177409259;4173.332266837291;4177.603127702863;4184.748915065045;4253.3194688849635;4253.574642535784;4260.233158031584;4267.21056604523;4276.102018576134;4729.881752956135;4882.584980276645;4883.4128908105;4887.95112048882;4897.657163998557;5791;5807.593468457168;5815.680137592473;5828.562857785683;5839.883202335929;1428.3215472650409;1454.1347898000672;1455.4105587237186;1457.693884284291;1459.2339022013527;937.8950772758944;980.1472718734603;989.7723523589615;999.4503023337136;1001.6093723447862;5118;5135.101357262464;5136.280920671109;5145.639805213674;5156.303898973591;6063.878722425527;6077.088436734525;6079.665618390943;6102.542309326818;6110.114033465212;4878.243872199815;5069.670471419346;5084;5094.898102037939;5153.3558305211845;3205.5832741743425;3244.967550324514;3258.5926759155104;3259;3263.298390845932;5444.91832622511;5674;5674.319081710221;5679.744411501492;5683.175939488751;6161.196655521727;6175.475084617768;6180.134781130645;6213.5339849511465;6233.438990490878;5493.890122197614;5644.8024319148635;5656.717164141776;5674.460926212086;5677.120046392815;6088.6677855030275;6094.177286066501;6110.51115910735;6140.8771824564155;6149.754009839557;5853.970730146322;5984.700764961647;5998.820035398945;6027.879442411039;6055.697215139137;5653.208550802973;5654.688992105309;5657.482420323081;5661.745221465127;5680.971595142163;4171.937420938749;4198.811053502539;4200.15996800635;4204.936925946052;4216.198922204894;5805.738741756715;6042.456178943916;6062.454379105901;6098.542609304409;6134.6625935573975;5868.5305175585;5886.470217680429;5892.616979896293;5909.172715819875;5944.286685597756;5664.830055098354;5877.559183061202;5887.587868849139;5899.292084949833;5899.882002359843;5830.53355731549;5835.270591176103;5836.970815145897;5848.590598658053;5879.970600146972;5943.433939636184;5950.613210141327;5967.731452084579;5969.224000879858;5984.940150598438;5640.097584386478;5725.599208055396;5764.337101233412;5771.797987070432;5787.334456537552 13:12:02 INFO - PID 6881 | |5;yui.html;577.4139248662606;580.0921557762105;630.3996016432217;655.2431941108019;670.0752960913939;13698.315084245798;13713.588592342234;13882.236476993761;14090.36593353299;14189.432422703092;12868;12931.547395841148;12950.676233094176;12957.74084518309;12994.350282485886;877.903430622631;889.4885440871492;892.8794612727264;894.061235702513;897.4301318662631;899.8020435504178;901.6979311930511;936.8266870628921;943.8820147481565;946.4888959961612;969.3795970578835;973.7419583810296;976.2677991506371;976.7265165753565;978.1245785022401;976.1214906584061;977.1352353167463;980.2109302011878;982.052319511671;982.8378317577591;712.3126183233153;715.9821004474878;716.1048689138577;716.881714517104;718.5437247347919;13444.731105377936;13973.510927117604;14372.281385930663;14434.639134021734;14444.711105777826;13074.411651475706;13844.584662460142;13874.722505549833;14209.00536962413;14285.428762728996;9355;9356.672516461891;9441.386309889904;9493.715188544285;9581.329306948517;2471.666325046114;2478.1574264749893;2529.709083455408;2531.531666641677;2552.515022145805;52714.736426318006;55779;56007.71996139993;56235.43764562302;56278;10912.945435272773;10918.833319223759;10942.23404361687;11108;11193.776124477465;19877.701834472493;20121.295754648516;20184.798152018586;20198.697019544717;20289.492762680755;6547.34526547351;6579.868402632016;6611.66941652925;6658.5671931324305;6881.965590172017;3151.448496513101;3244.902652920416;3245.6429792722784;3258.185453636591;3306.5866766654167;3489.092835862694;3542.468629705513;3569.678728914358;3571.821408929543;3625.3111908737783;654.9214094308713;659.6207180871019;667.1493845347221;668.6589839181955;681.56039354616;457.63389288569675;470.7810867946353;475.370134571687;475.7121941225578;485.92468167434106;7316.6341682915645;7579.393648508106;8325.95837020811;9091.409058411182;9216.880117572995;443.7892001299357;445.3431188996205;468.2671618916385;488.9804407823647;492.5493173746054;3485.233248685285;3520.542329497149;3593.4789455529326;3606.8376923038527;3645.5078564393466;56629.71685141548;56809.43190568124;57466.425335746106;57922.7103864478;59546.40453595495;49431.50568494341;49475;49511.50488495069;49737.75131124321;50698.746506267235;44136;44905.326420103724;45176;46354.536454635025;46941.29588056182;7044.506884518035;7057.153141622833;7079.362857342658;7212.062431883822;7442.218567050381;24297.878510607334;24347.26958191257;24552.631710524714;24659.63010554879;25154.24537263885;29795.255118622208;30120.24699382533;30192.69807301899;30629;30953.845230773702;8768.60541275657;9048.366614336934;9117.179453849185;9241.353105282835;9264.72205833826;9612.807743845224;9657.661981830883;9661.71014869555;9667.951660241935;9681.177099946583;8984;9029.77425564366;9223.631054758003;9281.164695177466;9298.907010930076;8791.912080879109;8857.601407936787;9169.954150229207;9341.34610577253;9412.858807118037;8951.239144672776;9073.591688373906;9141.954290228507;9152.816943661222;9447.52762361893;6945.2707465715375;7207.603581803051;7308.321355999574;7337.559746415019;7484.812879678052;7699.88450173259;7752.65113069902;7842.843143136991;7967.800804979922;8134.552599606842 13:12:02 INFO - PID 6881 | __end_tp_report 13:12:02 INFO - PID 6881 | __start_cc_report 13:12:02 INFO - PID 6881 | _x_x_mozilla_cycle_collect,392 13:12:02 INFO - PID 6881 | __end_cc_report 13:12:02 INFO - PID 6881 | __startTimestamp1490904722307__endTimestamp 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | ------- Summary: start ------- 13:12:02 INFO - PID 6881 | Number of tests: 6 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#0] dojo.html Cycles:165 Average:5207.47 Median:5338.63 stddev:585.24 (11.0%) stddev-sans-first:553.70 13:12:02 INFO - PID 6881 | Values: 2725.3 2737.9 2750.3 2779.8 2780.3 5175.0 5176.1 5239.0 5245.2 5250.5 5216.9 5228.1 5248.8 5322.5 5360.0 4836.5 4870.4 4888.3 4894.2 4931.7 4860.1 4900.9 4949.3 4963.6 5007.5 4748.2 4800.4 4808.3 4861.0 4875.9 4808.7 4830.8 4841.0 4865.7 4889.1 4711.2 4744.8 4787.1 4800.7 4817.9 5103.6 5177.9 5201.8 5242.9 5273.2 5665.2 5675.0 5730.5 5742.5 5759.9 5715.6 5718.9 5728.6 5733.2 5734.8 5184.3 5307.2 5318.7 5323.1 5371.8 5644.0 5703.6 5709.8 5718.8 5720.2 5461.2 5546.2 5576.8 5605.8 5607.2 5648.2 5700.9 5729.0 5729.9 5735.5 5622.7 5676.7 5677.3 5687.8 5692.6 5459.0 5554.5 5594.6 5643.9 5661.2 4752.2 4753.0 4767.7 4800.6 4806.6 4073.2 4075.0 4080.7 4087.1 4129.6 4677.1 4794.2 4851.8 4895.7 4902.1 5679.1 5707.1 5712.0 5726.3 5755.7 4810.8 4838.6 4840.5 4849.6 4890.0 4911.3 5000.9 5029.9 5040.1 5058.7 5284.2 5297.2 5308.8 5334.9 5358.4 5543.6 5604.0 5644.1 5665.0 5668.7 5219.2 5253.7 5310.5 5332.2 5338.6 5392.6 5479.0 5554.0 5554.3 5621.2 5703.3 5712.1 5736.9 5771.6 5786.9 5446.0 5501.7 5503.8 5512.1 5524.3 5382.1 5428.7 5504.9 5510.2 5545.7 5351.8 5444.7 5476.0 5477.9 5492.6 5569.8 5581.8 5582.0 5584.2 5585.6 5286.6 5409.1 5455.9 5462.3 5495.5 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#1] ext.html Cycles:180 Average:35156.74 Median:31382.45 stddev:55968.65 (178.3%) stddev-sans-first:56065.75 13:12:02 INFO - PID 6881 | Values: 669.3 714.9 747.1 1071.6 1093.2 33610.3 34495.3 34558.8 34611.3 34711.3 36250.8 36501.5 36546.1 36598.0 36679.4 7246.4 7336.6 7377.3 7580.1 7607.8 7617.5 7673.1 7729.1 7750.8 7751.6 7686.5 7749.4 7758.9 7765.7 7779.6 7666.6 7700.5 7710.8 7715.8 7727.6 7461.2 7466.5 7467.9 7492.0 7501.4 34463.5 34670.3 34871.7 34886.8 34896.1 2561.6 2562.3 2587.0 2626.1 2643.1 24045.9 24086.5 24513.9 24671.6 25101.0 2463.3 2479.2 2484.2 2509.1 2532.8 127604.4 130439.0 142659.0 144330.0 145246.3 42211.8 42384.5 42432.4 42531.9 42598.0 43700.1 43779.8 43803.0 43817.3 43820.0 11563.0 11597.6 11663.7 11686.1 11717.1 9229.1 9331.2 9381.0 9409.4 9436.8 13147.2 13161.7 13356.3 13364.6 13466.8 5422.4 5524.9 5579.7 5648.4 5714.9 867.5 896.3 896.4 906.0 926.1 20590.9 21515.8 21566.0 21615.0 21919.5 909.6 978.5 982.9 989.9 1011.5 11594.8 11662.8 11702.8 11747.2 11766.4 239625.0 240511.0 242609.0 242891.0 242983.0 43446.8 43758.1 43788.1 43832.0 44021.8 1221.8 1287.9 1292.0 1293.1 1298.7 20117.5 20261.8 21075.5 21255.7 21616.7 31253.7 31418.2 31477.0 31544.0 31549.2 244621.0 244960.0 245155.0 245226.0 245294.0 29555.0 30632.0 31159.8 31198.4 31246.7 37193.3 37448.6 38151.2 38178.2 38653.6 28294.7 28391.6 28564.9 28722.7 29354.7 28261.6 28301.0 28624.4 29451.4 29612.3 30925.4 31346.7 31495.5 31918.9 31973.8 27536.9 28099.2 28133.0 28273.9 28493.1 20704.5 22579.9 22672.2 22851.3 22887.0 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#2] jquery.html Cycles:180 Average:28444.31 Median:22053.10 stddev:77298.56 (350.5%) stddev-sans-first:77486.74 13:12:02 INFO - PID 6881 | Values: 411.8 421.2 431.8 437.6 439.5 17679.0 18283.9 18848.6 18961.3 19205.4 16414.0 16642.6 17014.1 17123.4 17477.4 5550.4 5677.2 5783.6 5825.1 5856.1 5757.4 5787.0 5826.1 5832.2 5835.5 5532.3 5774.9 5782.8 5792.5 5802.7 5689.4 5749.2 5781.6 5799.7 5801.6 5165.8 5205.2 5239.8 5240.8 5251.8 22537.2 22886.2 22939.4 23033.7 23037.9 1484.6 1550.4 1565.2 1566.4 1619.5 13896.9 14502.0 14639.3 14849.7 15318.4 1635.8 1646.1 1659.3 1668.3 1673.2 88521.0 88754.6 88771.0 90261.5 92788.0 11084.6 11313.3 12080.3 12132.0 12234.4 15649.7 15665.1 15704.8 15753.0 15832.4 7052.5 7239.2 7271.9 7443.6 7531.7 6508.8 6590.3 6725.3 6762.7 6796.6 7009.6 7064.7 7202.9 7345.6 7377.7 2057.3 2103.5 2113.8 2115.5 2217.8 812.8 850.7 878.8 879.8 888.4 16899.2 17040.8 17486.2 17591.0 17891.0 843.1 864.5 868.4 889.4 894.2 9241.5 9827.9 9844.8 9856.3 9870.6 463540.0 464439.0 464811.0 468679.0 475282.0 87833.0 87848.0 88263.0 91654.0 91782.5 998.7 1028.4 1053.0 1054.6 1059.0 17148.2 17916.5 18439.9 18604.3 18724.6 42300.1 42377.4 42856.8 43053.6 43674.3 54022.7 54528.5 54786.5 55586.0 56779.7 8130.4 8202.5 8468.6 8641.0 8652.2 13172.6 13312.4 13359.8 13546.1 13744.5 10910.9 10995.2 11243.5 11414.7 11935.3 10455.4 10749.4 10966.2 11038.0 11319.7 10874.3 10903.5 10970.9 11359.5 11473.5 8445.4 8458.5 8524.7 8561.3 8815.2 11273.3 11682.2 11844.8 11932.5 12335.3 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#3] mootools.html Cycles:180 Average:6139.31 Median:4679.71 stddev:3885.94 (83.0%) stddev-sans-first:3879.41 13:12:02 INFO - PID 6881 | Values: 1240.6 1325.2 1332.0 1333.5 1343.1 9567.4 9923.0 9929.4 9962.7 10195.5 9970.2 10150.2 10169.6 10176.1 10185.7 2143.5 2175.8 2193.4 2193.6 2196.0 2144.3 2175.3 2184.1 2197.4 2206.9 2110.9 2171.2 2182.4 2190.4 2199.8 2144.7 2149.3 2151.3 2156.3 2156.5 1796.7 1866.3 2031.7 2073.7 2086.7 9875.1 9960.8 9966.1 9972.3 10009.2 5091.4 5285.0 5294.3 5305.2 5315.9 3158.8 3205.1 3277.0 3343.8 3376.7 971.6 998.7 998.8 1004.8 1007.5 13997.3 14037.5 14038.0 14222.3 14241.6 4461.9 4660.7 4683.5 4707.7 4738.9 10675.3 10691.1 10703.2 10703.7 10716.4 5336.6 5366.9 5382.2 5391.4 5393.3 4675.9 4852.4 4889.1 4951.6 4963.8 3690.9 3777.5 3808.8 3853.8 3854.7 1154.2 1158.3 1161.7 1177.0 1178.8 692.0 699.7 721.4 730.8 742.4 5273.2 5299.0 5381.7 5482.9 5530.2 551.3 552.0 553.3 557.6 561.1 3669.7 3928.9 3990.2 4040.1 4076.0 11483.1 11902.1 12074.8 12655.6 12900.4 12334.1 12770.3 12832.8 12899.3 12936.7 9058.7 9269.5 9425.5 9469.3 9490.1 6166.5 6306.8 6352.8 6373.7 6476.5 11231.2 11591.0 11597.9 11706.3 11754.6 11589.8 11702.9 11714.5 11731.6 11904.4 8217.3 8343.5 8354.3 8407.4 8425.9 8943.6 8979.8 9096.0 9137.9 9143.1 7673.6 7685.8 7690.7 7695.7 7742.7 7376.5 7401.3 7438.1 7627.5 7658.3 6939.3 6959.9 6990.8 7031.7 7034.8 5212.9 5282.5 5315.1 5351.5 5470.5 5227.6 5354.6 5379.6 5454.3 5495.8 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#4] prototype.html Cycles:180 Average:4844.61 Median:5507.14 stddev:1429.28 (26.0%) stddev-sans-first:1393.33 13:12:02 INFO - PID 6881 | Values: 372.9 385.7 387.2 393.4 398.5 5509.4 5525.3 5552.2 5561.3 5660.4 5422.2 5496.8 5504.8 5512.3 5548.9 4173.0 4290.4 4308.7 4321.8 4328.8 4286.6 4302.1 4302.3 4311.1 4329.5 4269.5 4275.4 4289.3 4295.8 4315.5 4149.6 4254.4 4263.3 4268.4 4308.8 3675.2 3722.8 3730.2 3737.0 3739.9 5428.2 5436.1 5440.0 5440.2 5441.4 4710.8 4842.5 4858.7 4871.1 4879.3 5817.3 5834.9 5838.2 5841.6 5843.4 4043.4 4044.6 4045.2 4053.7 4054.9 6044.0 6141.9 6172.6 6196.1 6225.8 4152.4 4157.4 4173.3 4177.6 4184.7 4253.3 4253.6 4260.2 4267.2 4276.1 4729.9 4882.6 4883.4 4888.0 4897.7 5791.0 5807.6 5815.7 5828.6 5839.9 1428.3 1454.1 1455.4 1457.7 1459.2 937.9 980.1 989.8 999.5 1001.6 5118.0 5135.1 5136.3 5145.6 5156.3 6063.9 6077.1 6079.7 6102.5 6110.1 4878.2 5069.7 5084.0 5094.9 5153.4 3205.6 3245.0 3258.6 3259.0 3263.3 5444.9 5674.0 5674.3 5679.7 5683.2 6161.2 6175.5 6180.1 6213.5 6233.4 5493.9 5644.8 5656.7 5674.5 5677.1 6088.7 6094.2 6110.5 6140.9 6149.8 5854.0 5984.7 5998.8 6027.9 6055.7 5653.2 5654.7 5657.5 5661.7 5681.0 4171.9 4198.8 4200.2 4204.9 4216.2 5805.7 6042.5 6062.5 6098.5 6134.7 5868.5 5886.5 5892.6 5909.2 5944.3 5664.8 5877.6 5887.6 5899.3 5899.9 5830.5 5835.3 5837.0 5848.6 5880.0 5943.4 5950.6 5967.7 5969.2 5984.9 5640.1 5725.6 5764.3 5771.8 5787.3 13:12:02 INFO - PID 6881 | 13:12:02 INFO - PID 6881 | [#5] yui.html Cycles:180 Average:12827.11 Median:30063.25 stddev:15565.80 (51.8%) stddev-sans-first:15582.29 13:12:02 INFO - PID 6881 | Values: 577.4 580.1 630.4 655.2 670.1 13698.3 13713.6 13882.2 14090.4 14189.4 12868.0 12931.5 12950.7 12957.7 12994.4 877.9 889.5 892.9 894.1 897.4 899.8 901.7 936.8 943.9 946.5 969.4 973.7 976.3 976.7 978.1 976.1 977.1 980.2 982.1 982.8 712.3 716.0 716.1 716.9 718.5 13444.7 13973.5 14372.3 14434.6 14444.7 13074.4 13844.6 13874.7 14209.0 14285.4 9355.0 9356.7 9441.4 9493.7 9581.3 2471.7 2478.2 2529.7 2531.5 2552.5 52714.7 55779.0 56007.7 56235.4 56278.0 10912.9 10918.8 10942.2 11108.0 11193.8 19877.7 20121.3 20184.8 20198.7 20289.5 6547.3 6579.9 6611.7 6658.6 6882.0 3151.4 3244.9 3245.6 3258.2 3306.6 3489.1 3542.5 3569.7 3571.8 3625.3 654.9 659.6 667.1 668.7 681.6 457.6 470.8 475.4 475.7 485.9 7316.6 7579.4 8326.0 9091.4 9216.9 443.8 445.3 468.3 489.0 492.5 3485.2 3520.5 3593.5 3606.8 3645.5 56629.7 56809.4 57466.4 57922.7 59546.4 49431.5 49475.0 49511.5 49737.8 50698.7 44136.0 44905.3 45176.0 46354.5 46941.3 7044.5 7057.2 7079.4 7212.1 7442.2 24297.9 24347.3 24552.6 24659.6 25154.2 29795.3 30120.2 30192.7 30629.0 30953.8 8768.6 9048.4 9117.2 9241.4 9264.7 9612.8 9657.7 9661.7 9668.0 9681.2 8984.0 9029.8 9223.6 9281.2 9298.9 8791.9 8857.6 9170.0 9341.3 9412.9 8951.2 9073.6 9142.0 9152.8 9447.5 6945.3 7207.6 7308.3 7337.6 7484.8 7699.9 7752.7 7842.8 7967.8 8134.6 13:12:02 INFO - PID 6881 | -------- Summary: end -------- 13:12:02 INFO - PID 6881 | 13:12:03 INFO - TEST-INFO | 6881: exit 0 13:12:03 INFO - TEST-OK | dromaeo_css | took 1121529ms 13:12:03 INFO - TEST-START | kraken 13:12:03 INFO - operating with platform_type : linux_ 13:12:03 INFO - Initialising browser for kraken test... 13:12:03 INFO - TEST-INFO | started process 7261 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp19CnwR/profile http://localhost:47359/getInfo.html) 13:12:07 INFO - PID 7261 | __metrics Screen width/height:1600/1200 13:12:07 INFO - PID 7261 | colorDepth:24 13:12:07 INFO - PID 7261 | Browser inner width/height: 1024/697 13:12:07 INFO - PID 7261 | __metrics 13:12:08 ERROR - PID 7261 | 1490904728835 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6398:36 < canClose@browser.js:5243:12 < forceQuit@jar:file:///tmp/tmp19CnwR/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmp19CnwR/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmp19CnwR/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmp19CnwR/profile/extensions/talos-powers@TalosPowersService.js:55:9 13:12:08 ERROR - PID 7261 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 13:12:09 INFO - TEST-INFO | 7261: exit 0 13:12:09 INFO - Browser initialized. 13:12:09 INFO - Running cycle 1/1 for kraken test... 13:12:09 INFO - TEST-INFO | started process 7395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp19CnwR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:21 INFO - PID 7395 | RSS: Main: 156487680 13:12:21 INFO - PID 7395 | 13:12:24 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-beat-detection) 13:12:25 INFO - PID 7395 | RSS: Main: 162619392 13:12:25 INFO - PID 7395 | 13:12:28 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-dft) 13:12:28 INFO - PID 7395 | RSS: Main: 161185792 13:12:28 INFO - PID 7395 | 13:12:31 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-fft) 13:12:32 INFO - PID 7395 | RSS: Main: 157913088 13:12:32 INFO - PID 7395 | 13:12:34 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47359/tests/kraken/driver.html?testName=audio-oscillator) 13:12:35 INFO - PID 7395 | RSS: Main: 158826496 13:12:35 INFO - PID 7395 | 13:12:37 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:12:38 INFO - PID 7395 | RSS: Main: 158883840 13:12:38 INFO - PID 7395 | 13:12:41 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-darkroom) 13:12:42 INFO - PID 7395 | RSS: Main: 164270080 13:12:42 INFO - PID 7395 | 13:12:45 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47359/tests/kraken/driver.html?testName=imaging-desaturate) 13:12:46 INFO - PID 7395 | RSS: Main: 161562624 13:12:46 INFO - PID 7395 | 13:12:49 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47359/tests/kraken/driver.html?testName=json-parse-financial) 13:12:49 INFO - PID 7395 | RSS: Main: 162856960 13:12:49 INFO - PID 7395 | 13:12:51 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:12:52 INFO - PID 7395 | RSS: Main: 163340288 13:12:52 INFO - PID 7395 | 13:12:54 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:12:54 INFO - PID 7395 | RSS: Main: 163319808 13:12:54 INFO - PID 7395 | 13:12:56 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:12:57 INFO - PID 7395 | RSS: Main: 163532800 13:12:57 INFO - PID 7395 | 13:13:00 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:13:01 INFO - PID 7395 | RSS: Main: 163864576 13:13:01 INFO - PID 7395 | 13:13:04 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:13:05 INFO - PID 7395 | RSS: Main: 167067648 13:13:05 INFO - PID 7395 | 13:13:06 INFO - PID 7395 | Cycle 1(1): loaded http://localhost:47359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47359/tests/kraken/driver.html?testName=ai-astar) 13:13:07 INFO - PID 7395 | RSS: Main: 167550976 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | __start_tp_report 13:13:07 INFO - PID 7395 | _x_x_mozilla_page_load 13:13:07 INFO - PID 7395 | _x_x_mozilla_page_load_details 13:13:07 INFO - PID 7395 | |i|pagename|runs| 13:13:07 INFO - PID 7395 | |0;ai-astar;107;111;112;114;113;115;116;114;112;113 13:13:07 INFO - PID 7395 | |1;audio-beat-detection;150;127;127;178;126;130;125;126;150;124 13:13:07 INFO - PID 7395 | |2;audio-dft;167;167;167;168;171;169;172;168;168;167 13:13:07 INFO - PID 7395 | |3;audio-fft;76;76;75;119;78;76;76;75;98;76 13:13:07 INFO - PID 7395 | |4;audio-oscillator;85;84;104;85;84;84;101;84;85;109 13:13:07 INFO - PID 7395 | |5;imaging-gaussian-blur;114;115;115;115;115;118;116;115;115;114 13:13:07 INFO - PID 7395 | |6;imaging-darkroom;151;154;151;153;151;152;151;151;152;152 13:13:07 INFO - PID 7395 | |7;imaging-desaturate;95;95;94;95;95;95;96;95;96;96 13:13:07 INFO - PID 7395 | |8;json-parse-financial;67;68;71;69;68;69;68;68;67;69 13:13:07 INFO - PID 7395 | |9;json-stringify-tinderbox;57;56;57;56;56;56;55;56;56;56 13:13:07 INFO - PID 7395 | |10;stanford-crypto-aes;85;83;83;84;84;92;84;83;84;95 13:13:07 INFO - PID 7395 | |11;stanford-crypto-ccm;138;144;137;136;135;150;140;149;218;135 13:13:07 INFO - PID 7395 | |12;stanford-crypto-pbkdf2;191;194;198;195;196;199;189;197;202;199 13:13:07 INFO - PID 7395 | |13;stanford-crypto-sha256-iterative;73;74;72;73;71;75;74;71;72;73 13:13:07 INFO - PID 7395 | __end_tp_report 13:13:07 INFO - PID 7395 | __start_cc_report 13:13:07 INFO - PID 7395 | _x_x_mozilla_cycle_collect,901 13:13:07 INFO - PID 7395 | __end_cc_report 13:13:07 INFO - PID 7395 | __startTimestamp1490904787121__endTimestamp 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | ------- Summary: start ------- 13:13:07 INFO - PID 7395 | Number of tests: 14 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:2.50 (2.2%) stddev-sans-first:1.58 13:13:07 INFO - PID 7395 | Values: 107.0 111.0 112.0 114.0 113.0 115.0 116.0 114.0 112.0 113.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:128.50 stddev:17.70 (13.8%) stddev-sans-first:18.06 13:13:07 INFO - PID 7395 | Values: 150.0 127.0 127.0 178.0 126.0 130.0 125.0 126.0 150.0 124.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#2] audio-dft Cycles:10 Average:168.40 Median:168.00 stddev:1.78 (1.1%) stddev-sans-first:1.81 13:13:07 INFO - PID 7395 | Values: 167.0 167.0 167.0 168.0 171.0 169.0 172.0 168.0 168.0 167.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#3] audio-fft Cycles:10 Average:82.50 Median:76.00 stddev:14.59 (19.2%) stddev-sans-first:15.29 13:13:07 INFO - PID 7395 | Values: 76.0 76.0 75.0 119.0 78.0 76.0 76.0 75.0 98.0 76.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:84.00 stddev:9.97 (11.9%) stddev-sans-first:10.37 13:13:07 INFO - PID 7395 | Values: 85.0 84.0 104.0 85.0 84.0 84.0 101.0 84.0 85.0 109.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.00 stddev:1.14 (1.0%) stddev-sans-first:1.12 13:13:07 INFO - PID 7395 | Values: 114.0 115.0 115.0 115.0 115.0 118.0 116.0 115.0 115.0 114.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 13:13:07 INFO - PID 7395 | Values: 151.0 154.0 151.0 153.0 151.0 152.0 151.0 151.0 152.0 152.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 13:13:07 INFO - PID 7395 | Values: 95.0 95.0 94.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:68.50 stddev:1.17 (1.7%) stddev-sans-first:1.13 13:13:07 INFO - PID 7395 | Values: 67.0 68.0 71.0 69.0 68.0 69.0 68.0 68.0 67.0 69.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.57 (1.0%) stddev-sans-first:0.50 13:13:07 INFO - PID 7395 | Values: 57.0 56.0 57.0 56.0 56.0 56.0 55.0 56.0 56.0 56.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:84.00 stddev:4.22 (5.0%) stddev-sans-first:4.47 13:13:07 INFO - PID 7395 | Values: 85.0 83.0 83.0 84.0 84.0 92.0 84.0 83.0 84.0 95.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:142.00 stddev:25.14 (17.7%) stddev-sans-first:26.39 13:13:07 INFO - PID 7395 | Values: 138.0 144.0 137.0 136.0 135.0 150.0 140.0 149.0 218.0 135.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.00 Median:197.50 stddev:3.92 (2.0%) stddev-sans-first:3.71 13:13:07 INFO - PID 7395 | Values: 191.0 194.0 198.0 195.0 196.0 199.0 189.0 197.0 202.0 199.0 13:13:07 INFO - PID 7395 | 13:13:07 INFO - PID 7395 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:73.00 stddev:1.32 (1.8%) stddev-sans-first:1.39 13:13:07 INFO - PID 7395 | Values: 73.0 74.0 72.0 73.0 71.0 75.0 74.0 71.0 72.0 73.0 13:13:07 INFO - PID 7395 | -------- Summary: end -------- 13:13:07 INFO - PID 7395 | 13:13:08 INFO - TEST-INFO | 7395: exit 0 13:13:08 INFO - TEST-OK | kraken | took 65060ms 13:13:08 INFO - SUITE-END | took 1186s 13:13:08 INFO - Completed test suite (00:19:47) 13:13:08 INFO - javascript benchmark 13:13:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6611.654595085278, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2725.2641786717586, 2737.931551711208, 2750.2711781377925, 2779.7637200837917, 2780.332720147164, 5175.0, 5176.068307704616, 5239.0, 5245.186996015615, 5250.527452529272, 5216.8695782605355, 5228.111221092414, 5248.8425347239545, 5322.547583455411, 5359.99999999999, 4836.51634836517, 4870.366852309213, 4888.291197776321, 4894.192458244385, 4931.679440836352, 4860.1008813369635, 4900.926486102711, 4949.331840201563, 4963.627727920402, 5007.474215207405, 4748.169070412664, 4800.447948485934, 4808.326834243174, 4860.951390486085, 4875.926861097087, 4808.687435316729, 4830.758462076882, 4840.975795121037, 4865.732384718839, 4889.095517329305, 4711.246200607886, 4744.833930812405, 4787.13831510328, 4800.687955282895, 4817.855464336076, 5103.56619687327, 5177.922331165036, 5201.791928322863, 5242.9213561796605, 5273.235380869753, 5665.150227465913, 5675.0, 5730.541556675456, 5742.511886489653, 5759.856003599902, 5715.6284841485585, 5718.857028574319, 5728.570357223225, 5733.168690539848, 5734.8279551613105, 5184.326037615086, 5307.177387504981, 5318.654287471302, 5323.1216849219445, 5371.785128594813, 5644.0, 5703.62926409782, 5709.8287051388525, 5718.771249150071, 5720.1991721159075, 5461.208124821878, 5546.195801608825, 5576.804811831566, 5605.80379686709, 5607.186957891083, 5648.237487939158, 5700.885982280414, 5728.971355143198, 5729.8567535811935, 5735.483806457355, 5622.690752008597, 5676.744546495418, 5677.290338707661, 5687.829365119053, 5692.6299790513485, 5459.044667183227, 5554.527865131428, 5594.608377413623, 5643.858903527444, 5661.207430959669, 4752.215884379108, 4752.95247047532, 4767.6662633615315, 4800.591949684246, 4806.639502037361, 4073.165001174752, 4075.0219947212454, 4080.6531444827524, 4087.1212689271947, 4129.607687269705, 4677.134730074947, 4794.161021821167, 4851.80592776285, 4895.6817806843155, 4902.117618828645, 5679.091345384719, 5707.143928410772, 5712.0, 5726.255586773693, 5755.712214389264, 4810.759462026885, 4838.612910967114, 4840.515948405202, 4849.612031037508, 4889.951100489021, 4911.263310503382, 5000.924986125211, 5029.924551131663, 5040.143175660147, 5058.696478211319, 5284.180951952299, 5297.231901374202, 5308.787648494172, 5334.893302133858, 5358.356997160366, 5543.556515478671, 5603.971980140073, 5644.096944488862, 5665.0, 5668.659880407189, 5219.243209734643, 5253.711045892513, 5310.548603368757, 5332.173513105475, 5338.626296159232, 5392.568594512508, 5479.000329759218, 5554.0, 5554.333479982428, 5621.213030175697, 5703.287089113861, 5712.143178523254, 5736.885262294814, 5771.624844385102, 5786.855328616818, 5445.972770136124, 5501.724913754377, 5503.807366742145, 5512.090505066619, 5524.33707955048, 5382.058139825436, 5428.728563571726, 5504.862378440411, 5510.228568000403, 5545.694986775766, 5351.759170837399, 5444.700541470257, 5476.0, 5477.890442191054, 5492.588055895744, 5569.777208911761, 5581.776728930798, 5581.967336042845, 5584.218209450762, 5585.5531557476115, 5286.603504737083, 5409.0804563223555, 5455.9454405455435, 5462.3445186577865, 5495.53287970527], "value": 5166.135348374657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.3273260373327, 714.8606021825743, 747.1072068877692, 1071.5927947379994, 1093.1582681335376, 33610.327793444114, 34495.31009379811, 34558.827205863934, 34611.30777384457, 34711.30577388451, 36250.81874590623, 36501.45247821285, 36546.086347841316, 36598.0, 36679.44980825283, 7246.420286377091, 7336.633168341588, 7377.262273772633, 7580.05249343832, 7607.847843043136, 7617.504862183968, 7673.079230492349, 7729.072511298652, 7750.806229844243, 7751.6124193790365, 7686.500377475474, 7749.418793590475, 7758.883616745753, 7765.689372425096, 7779.649915753776, 7666.578338191397, 7700.499467534621, 7710.845783084335, 7715.807104822368, 7727.613619319011, 7461.216572259888, 7466.4773465857415, 7467.8506429871095, 7491.962540187319, 7501.3623841973495, 34463.48304775431, 34670.306593868234, 34871.651283487095, 34886.82556587226, 34896.12759681003, 2561.6029515424952, 2562.2627878091985, 2587.0, 2626.054620336677, 2643.070558492311, 24045.87977060121, 24086.518269634686, 24513.877430612913, 24671.56690009783, 25100.99596016157, 2463.322586288767, 2479.2438306316567, 2484.229888734498, 2509.1092662105057, 2532.7973762098986, 127604.36197819044, 130439.0, 142659.0, 144330.0, 145246.2737686305, 42211.7889410551, 42384.516541920886, 42432.36351454731, 42531.93670158271, 42598.0, 43700.125997479874, 43779.78110109429, 43803.0, 43817.34273985893, 43820.0, 11563.0, 11597.594084207181, 11663.650090497298, 11686.12354073448, 11717.12121590884, 9229.077092290718, 9331.20684741791, 9381.0, 9409.388389754644, 9436.811263774685, 13147.211167329991, 13161.670958226121, 13356.332183390792, 13364.599062028155, 13466.797998030037, 5422.43064478232, 5524.917126243109, 5579.721013949286, 5648.378678345379, 5714.9142762857755, 867.5185272173878, 896.3232759266712, 896.4038914122166, 906.0395980260945, 926.1109335038304, 20590.897045514976, 21515.78484215169, 21566.0, 21615.001224773467, 21919.452013699785, 909.6497848328441, 978.5400861595039, 982.9459379734184, 989.8911119776819, 1011.5346940407347, 11594.821807203263, 11662.825057624143, 11702.824457633142, 11747.236429632047, 11766.411679415995, 239625.0, 240511.0, 242609.0, 242891.0, 242983.0, 43446.78276608597, 43758.12483750371, 43788.12423751507, 43832.0, 44021.77989110034, 1221.780079585683, 1287.9162854414433, 1292.0309767674244, 1293.1142167615026, 1298.733759579284, 20117.497062572966, 20261.79738202599, 21075.473113172295, 21255.681164782232, 21616.67574986408, 31253.68746312508, 31418.21454463657, 31477.0, 31544.0, 31549.21126971752, 244620.99205535438, 244960.0, 245155.0, 245226.0, 245294.0, 29555.0, 30632.0, 31159.84420077885, 31198.37603247968, 31246.68753312438, 37193.256134877694, 37448.62551374451, 38151.236975260894, 38178.2364352717, 38653.613463865004, 28294.71705283003, 28391.574126388536, 28564.857175713987, 28722.71277287284, 29354.70645293605, 28261.57607635928, 28301.0, 28624.42751145007, 29451.41097178087, 29612.259693507836, 30925.381492370478, 31346.686533134376, 31495.52756708697, 31918.88283910052, 31973.840130800127, 27536.862315689094, 28099.157025289274, 28133.0, 28273.858630706716, 28493.145205643865, 20704.48238794042, 22579.887100564392, 22672.206472773374, 22851.31446056621, 22886.97008634582], "value": 15116.502394746398, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.76323613922, 421.2004212004211, 431.8488529014845, 437.61927123402626, 439.5494618016533, 17678.999999999985, 18283.908580457097, 18848.62302753944, 18961.336353227623, 19205.42383728487, 16414.0, 16642.58393540162, 17014.149292535385, 17123.400680976167, 17477.388291409803, 5550.361708403525, 5677.17680936263, 5783.566232532556, 5825.097109947965, 5856.063029915214, 5757.395473475288, 5787.0, 5826.067829147316, 5832.154337621042, 5835.474807267345, 5532.28080349554, 5774.855628609297, 5782.797602083928, 5792.507636850873, 5802.651840889539, 5689.374168841424, 5749.166370876221, 5781.62419442737, 5799.652020878761, 5801.564882633798, 5165.793368265265, 5205.24523944026, 5239.79040838366, 5240.79036838526, 5251.789928402859, 22537.211197608005, 22886.198983035512, 22939.42651433711, 23033.654495182585, 23037.884810576008, 1484.5620541940045, 1550.434091556585, 1565.24085818378, 1566.4360830101155, 1619.4898606938777, 13896.861031389657, 14502.0, 14639.268036598127, 14849.70300593982, 15318.387264509407, 1635.7628143919185, 1646.1275523972313, 1659.2616285752845, 1668.2659629762866, 1673.171779968911, 88521.0, 88754.5562272191, 88771.0, 90261.54869225612, 92788.0, 11084.556617735361, 11313.321200727984, 12080.275183489019, 12132.0, 12234.388280585936, 15649.687006259812, 15665.138417387154, 15704.842951570337, 15753.0, 15832.445864394922, 7052.541584797074, 7239.20368759436, 7271.927280727231, 7443.590602516913, 7531.661075251627, 6508.837279067967, 6590.275069742324, 6725.2602213756445, 6762.69567869447, 6796.592204467649, 7009.61447120403, 7064.682089305994, 7202.927970720226, 7345.595992220372, 7377.741779037708, 2057.3005178239173, 2103.516191276015, 2113.778053304381, 2115.513431910669, 2217.800397964191, 812.8333691593209, 850.7405241401432, 878.7715194049593, 879.7536689726886, 888.3825741109996, 16899.23953422099, 17040.829591703925, 17486.21312040961, 17591.0, 17891.0, 843.1484200957077, 864.4553931023415, 868.3660927522943, 889.3774357949461, 894.1594900793234, 9241.486530929498, 9827.901720982842, 9844.80310393788, 9856.31005829585, 9870.605175792887, 463540.0, 464439.0, 464811.0, 468679.0, 475282.0, 87833.0, 87848.0, 88263.0, 91654.0, 91782.54108729414, 998.7203582996773, 1028.4446398944635, 1052.9838705648933, 1054.6361505280665, 1058.9682309530726, 17148.228329725443, 17916.462506124837, 18439.90780046091, 18604.25582976666, 18724.6255074895, 42300.096495657155, 42377.36433953555, 42856.78571607122, 43053.56946430496, 43674.34488482739, 54022.72988635032, 54528.45471545234, 54786.45213547813, 55586.0, 56779.71610142088, 8130.4308698392915, 8202.548859812767, 8468.57657117154, 8640.956795215983, 8652.178043085896, 13172.60482185536, 13312.400941957829, 13359.799603005767, 13546.05177637556, 13744.450221991008, 10910.945445272722, 10995.175361848052, 11243.49404276826, 11414.714632134263, 11935.343556104497, 10455.424951627734, 10749.408782517037, 10966.232363734462, 11038.0, 11319.660410187707, 10874.293170943864, 10903.509342079467, 10970.890291096986, 11359.48882300282, 11473.483693233991, 8445.40882138269, 8458.450200736932, 8524.74425767228, 8561.315094792526, 8815.206631403205, 11273.309003649518, 11682.182247242612, 11844.763104737685, 11932.522699092286, 12335.321557314075], "value": 9080.409088942517, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1240.615409223141, 1325.1850112181005, 1331.9933400333, 1333.493272556429, 1343.100122917645, 9567.378120422176, 9923.0, 9929.40423574586, 9962.701118966424, 10195.54120064597, 9970.202383809277, 10150.187984961203, 10169.644062457815, 10176.135028522585, 10185.694429167137, 2143.4962783745846, 2175.7715439878743, 2193.396815875631, 2193.56128774245, 2195.9780402195934, 2144.3138195777356, 2175.3473957812594, 2184.1045171479695, 2197.4286685461734, 2206.889655517226, 2110.8627939183903, 2171.2183613898983, 2182.4434769133927, 2190.3866917263113, 2199.802017818396, 2144.6997420361163, 2149.258505815499, 2151.333086743107, 2156.2884248198056, 2156.54712510373, 1796.7035439152523, 1866.337450205171, 2031.6647753120717, 2073.6785798201304, 2086.6869969504546, 9875.061869122423, 9960.750981225456, 9966.103050725469, 9972.301938864282, 10009.199264058858, 5091.439941606421, 5284.9735751320995, 5294.258803767476, 5305.204219367087, 5315.920261196085, 3158.7630927680284, 3205.1346136543, 3277.016894931511, 3343.765936384454, 3376.7467439941856, 971.5627967414621, 998.7353351361895, 998.8202123617746, 1004.829179039565, 1007.5113569918584, 13997.30013499321, 14037.490627343164, 14037.98183390576, 14222.288885555681, 14241.572752817328, 4461.933071003938, 4660.6970546914345, 4683.484816670163, 4707.741074240949, 4738.905221895543, 10675.306105103144, 10691.144708423308, 10703.197260205516, 10703.732406689895, 10716.35701857891, 5336.599755018389, 5366.946330536723, 5382.2464854920345, 5391.353037635509, 5393.298871146726, 4675.929861052087, 4852.441969173519, 4889.144399730033, 4951.554360107608, 4963.776630051656, 3690.944635830465, 3777.490038844776, 3808.771473711586, 3853.7687738735767, 3854.6530812226474, 1154.2382027861738, 1158.3455347728507, 1161.6631176958588, 1177.0465922602718, 1178.7701398227264, 691.9930800691928, 699.6641612026154, 721.3868212019742, 730.8428687832036, 742.3764038207931, 5273.156294992783, 5299.019681358961, 5381.650192737459, 5482.862928426821, 5530.198121272473, 551.2640624765983, 551.9834404967858, 553.297312413241, 557.637535601862, 561.0770282884632, 3669.7064234861145, 3928.9214215715524, 3990.182012687392, 4040.1313717550865, 4075.9796201018803, 11483.13876459269, 11902.107341949388, 12074.818877716842, 12655.62033138989, 12900.35498225085, 12334.136610437363, 12770.297633630054, 12832.80750788739, 12899.290539020443, 12936.67658308531, 9058.728238152999, 9269.490178040272, 9425.481598512011, 9469.289803264783, 9490.050994900455, 6166.475849552839, 6306.779262725782, 6352.841178970562, 6373.74505019794, 6476.5466417350335, 11231.213815033196, 11591.0, 11597.88402115968, 11706.297622142698, 11754.647360579196, 11589.826152607886, 11702.88297117018, 11714.472848721998, 11731.648050558497, 11904.404779760804, 8217.260446559838, 8343.499390036617, 8354.331653467587, 8407.411481196257, 8425.946756655418, 8943.552822358986, 8979.775505612412, 9096.0, 9137.862932056158, 9143.085691430804, 7673.577953212627, 7685.807854803451, 7690.692372305045, 7695.730649427243, 7742.65158067877, 7376.520526165782, 7401.296876796696, 7438.144613369532, 7627.54234745917, 7658.349040331635, 6939.340762627542, 6959.930400695928, 6990.790276291719, 7031.718731250693, 7034.824129396806, 5212.947870521398, 5282.47175282484, 5315.0964336063735, 5351.518363347317, 5470.452954704498, 5227.634065615371, 5354.571634269327, 5379.623426360117, 5454.318210223722, 5495.780168793363], "value": 4604.070471989182, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.9123655940853, 385.70300868331395, 387.1559999201741, 393.3902451200642, 398.4859531204748, 5509.449055094488, 5525.309336332958, 5552.194931734901, 5561.304836895388, 5660.377358490563, 5422.240886275915, 5496.780128794843, 5504.825593890411, 5512.283403157594, 5548.86127846804, 4172.958270417303, 4290.377895205193, 4308.655307575386, 4321.762303073329, 4328.761918094519, 4286.614204721574, 4302.139572085595, 4302.26861433557, 4311.094670119279, 4329.502107257674, 4269.466316710411, 4275.380069889864, 4289.313709806432, 4295.828166873322, 4315.482142142947, 4149.585041495857, 4254.361845723135, 4263.317869140923, 4268.42376279203, 4308.82764689412, 3675.2465744522574, 3722.7766334020043, 3730.235301763132, 3737.0, 3739.9065023374633, 5428.158635411518, 5436.130219164954, 5439.972800136014, 5440.211169380457, 5441.374241962186, 4710.764461776932, 4842.515748425165, 4858.708477491362, 4871.14754917888, 4879.341288925985, 5817.301923769132, 5834.912476312858, 5838.182654428383, 5841.620294680833, 5843.415658434165, 4043.413705012786, 4044.564179716188, 4045.1909618076206, 4053.7365071270274, 4054.878353649395, 6044.0, 6141.938580614182, 6172.611162488449, 6196.132541444202, 6225.782097626607, 4152.397902304149, 4157.397177409259, 4173.332266837291, 4177.603127702863, 4184.748915065045, 4253.3194688849635, 4253.574642535784, 4260.233158031584, 4267.21056604523, 4276.102018576134, 4729.881752956135, 4882.584980276645, 4883.4128908105, 4887.95112048882, 4897.657163998557, 5791.0, 5807.593468457168, 5815.680137592473, 5828.562857785683, 5839.883202335929, 1428.3215472650409, 1454.1347898000672, 1455.4105587237186, 1457.693884284291, 1459.2339022013527, 937.8950772758944, 980.1472718734603, 989.7723523589615, 999.4503023337136, 1001.6093723447862, 5118.0, 5135.101357262464, 5136.280920671109, 5145.639805213674, 5156.303898973591, 6063.878722425527, 6077.088436734525, 6079.665618390943, 6102.542309326818, 6110.114033465212, 4878.243872199815, 5069.670471419346, 5084.0, 5094.898102037939, 5153.3558305211845, 3205.5832741743425, 3244.967550324514, 3258.5926759155104, 3259.0, 3263.298390845932, 5444.91832622511, 5674.0, 5674.319081710221, 5679.744411501492, 5683.175939488751, 6161.196655521727, 6175.475084617768, 6180.134781130645, 6213.5339849511465, 6233.438990490878, 5493.890122197614, 5644.8024319148635, 5656.717164141776, 5674.460926212086, 5677.120046392815, 6088.6677855030275, 6094.177286066501, 6110.51115910735, 6140.8771824564155, 6149.754009839557, 5853.970730146322, 5984.700764961647, 5998.820035398945, 6027.879442411039, 6055.697215139137, 5653.208550802973, 5654.688992105309, 5657.482420323081, 5661.745221465127, 5680.971595142163, 4171.937420938749, 4198.811053502539, 4200.15996800635, 4204.936925946052, 4216.198922204894, 5805.738741756715, 6042.456178943916, 6062.454379105901, 6098.542609304409, 6134.6625935573975, 5868.5305175585, 5886.470217680429, 5892.616979896293, 5909.172715819875, 5944.286685597756, 5664.830055098354, 5877.559183061202, 5887.587868849139, 5899.292084949833, 5899.882002359843, 5830.53355731549, 5835.270591176103, 5836.970815145897, 5848.590598658053, 5879.970600146972, 5943.433939636184, 5950.613210141327, 5967.731452084579, 5969.224000879858, 5984.940150598438, 5640.097584386478, 5725.599208055396, 5764.337101233412, 5771.797987070432, 5787.334456537552], "value": 4401.752844082766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.4139248662606, 580.0921557762105, 630.3996016432217, 655.2431941108019, 670.0752960913939, 13698.315084245798, 13713.588592342234, 13882.236476993761, 14090.36593353299, 14189.432422703092, 12868.0, 12931.547395841148, 12950.676233094176, 12957.74084518309, 12994.350282485886, 877.903430622631, 889.4885440871492, 892.8794612727264, 894.061235702513, 897.4301318662631, 899.8020435504178, 901.6979311930511, 936.8266870628921, 943.8820147481565, 946.4888959961612, 969.3795970578835, 973.7419583810296, 976.2677991506371, 976.7265165753565, 978.1245785022401, 976.1214906584061, 977.1352353167463, 980.2109302011878, 982.052319511671, 982.8378317577591, 712.3126183233153, 715.9821004474878, 716.1048689138577, 716.881714517104, 718.5437247347919, 13444.731105377936, 13973.510927117604, 14372.281385930663, 14434.639134021734, 14444.711105777826, 13074.411651475706, 13844.584662460142, 13874.722505549833, 14209.00536962413, 14285.428762728996, 9355.0, 9356.672516461891, 9441.386309889904, 9493.715188544285, 9581.329306948517, 2471.666325046114, 2478.1574264749893, 2529.709083455408, 2531.531666641677, 2552.515022145805, 52714.736426318006, 55779.0, 56007.71996139993, 56235.43764562302, 56278.0, 10912.945435272773, 10918.833319223759, 10942.23404361687, 11108.0, 11193.776124477465, 19877.701834472493, 20121.295754648516, 20184.798152018586, 20198.697019544717, 20289.492762680755, 6547.34526547351, 6579.868402632016, 6611.66941652925, 6658.5671931324305, 6881.965590172017, 3151.448496513101, 3244.902652920416, 3245.6429792722784, 3258.185453636591, 3306.5866766654167, 3489.092835862694, 3542.468629705513, 3569.678728914358, 3571.821408929543, 3625.3111908737783, 654.9214094308713, 659.6207180871019, 667.1493845347221, 668.6589839181955, 681.56039354616, 457.63389288569675, 470.7810867946353, 475.370134571687, 475.7121941225578, 485.92468167434106, 7316.6341682915645, 7579.393648508106, 8325.95837020811, 9091.409058411182, 9216.880117572995, 443.7892001299357, 445.3431188996205, 468.2671618916385, 488.9804407823647, 492.5493173746054, 3485.233248685285, 3520.542329497149, 3593.4789455529326, 3606.8376923038527, 3645.5078564393466, 56629.71685141548, 56809.43190568124, 57466.425335746106, 57922.7103864478, 59546.40453595495, 49431.50568494341, 49475.0, 49511.50488495069, 49737.75131124321, 50698.746506267235, 44136.0, 44905.326420103724, 45176.0, 46354.536454635025, 46941.29588056182, 7044.506884518035, 7057.153141622833, 7079.362857342658, 7212.062431883822, 7442.218567050381, 24297.878510607334, 24347.26958191257, 24552.631710524714, 24659.63010554879, 25154.24537263885, 29795.255118622208, 30120.24699382533, 30192.69807301899, 30629.0, 30953.845230773702, 8768.60541275657, 9048.366614336934, 9117.179453849185, 9241.353105282835, 9264.72205833826, 9612.807743845224, 9657.661981830883, 9661.71014869555, 9667.951660241935, 9681.177099946583, 8984.0, 9029.77425564366, 9223.631054758003, 9281.164695177466, 9298.907010930076, 8791.912080879109, 8857.601407936787, 9169.954150229207, 9341.34610577253, 9412.858807118037, 8951.239144672776, 9073.591688373906, 9141.954290228507, 9152.816943661222, 9447.52762361893, 6945.2707465715375, 7207.603581803051, 7308.321355999574, 7337.559746415019, 7484.812879678052, 7699.88450173259, 7752.65113069902, 7842.843143136991, 7967.800804979922, 8134.552599606842], "value": 5812.147196035211, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 112.0, 114.0, 113.0, 115.0, 116.0, 114.0, 112.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 127.0, 127.0, 178.0, 126.0, 130.0, 125.0, 126.0, 150.0, 124.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 167.0, 168.0, 171.0, 169.0, 172.0, 168.0, 168.0, 167.0], "value": 168.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 119.0, 78.0, 76.0, 76.0, 75.0, 98.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 104.0, 85.0, 84.0, 84.0, 101.0, 84.0, 85.0, 109.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 115.0, 118.0, 116.0, 115.0, 115.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 154.0, 151.0, 153.0, 151.0, 152.0, 151.0, 151.0, 152.0, 152.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 69.0, 68.0, 69.0, 68.0, 68.0, 67.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 56.0, 56.0, 56.0, 55.0, 56.0, 56.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 83.0, 84.0, 84.0, 92.0, 84.0, 83.0, 84.0, 95.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 137.0, 136.0, 135.0, 150.0, 140.0, 149.0, 218.0, 135.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 194.0, 198.0, 195.0, 196.0, 199.0, 189.0, 197.0, 202.0, 199.0], "value": 196.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 73.0, 71.0, 75.0, 74.0, 71.0, 72.0, 73.0], "value": 72.8, "unit": "score"}]}]} 13:13:08 INFO - Return code: 0 13:13:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:13:08 ERROR - # TBPL SUCCESS # 13:13:08 INFO - Running post-action listener: _resource_record_post_action 13:13:08 INFO - [mozharness: 2017-03-30 20:13:08.930779Z] Finished run-tests step (success) 13:13:08 INFO - Running post-run listener: _resource_record_post_run 13:13:09 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 364617728; Read time: 12; Write time: 466984 13:13:09 INFO - TinderboxPrint: CPU usage
13.4% 13:13:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:13:09 INFO - TinderboxPrint: I/O write bytes / time
364,617,728 / 466,984 13:13:09 INFO - TinderboxPrint: CPU idle
8,315.4 (86.6%) 13:13:09 INFO - TinderboxPrint: CPU user
1,222.8 (12.7%) 13:13:09 INFO - TinderboxPrint: Swap in / out
0 / 0 13:13:09 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106684416; Read time: 0; Write time: 161908 13:13:09 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 257933312; Read time: 12; Write time: 305076 13:13:09 INFO - Running post-run listener: _upload_blobber_files 13:13:09 INFO - Blob upload gear active. 13:13:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:13:09 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 13:13:09 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:13:09 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:13:09 INFO - (blobuploader) - INFO - Open directory for files ... 13:13:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:13:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:13:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:11 INFO - (blobuploader) - INFO - Done attempting. 13:13:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:13:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:12 INFO - (blobuploader) - INFO - Done attempting. 13:13:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:13:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:12 INFO - (blobuploader) - INFO - Done attempting. 13:13:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:13:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:13:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:13:15 INFO - (blobuploader) - INFO - Done attempting. 13:13:15 INFO - (blobuploader) - INFO - Iteration through files over. 13:13:16 INFO - Return code: 0 13:13:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:13:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:13:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b25d2a510847fe9e112f0130f64c1a049c6a1ae546802e2eaf7e74906f7379ef65f6be13d7c1ab8568f5ffca1e0371434c2b3e42f61e2606fd750048cfd47ee", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f196521b5192e9b0d5d44f2178e23b94c78f270aff693c061c06cb69ffa1b79c5613b36cf0e7230fd3e5bcd8b3ca20668c8037d6513a1ca52382127b5a739e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/510bb877d3d49f8237c11c9e6e77809a46a11b3e79ce90c7718c83942642dd6cb59931e69c0ce33bd69b5c7ee3ea1c728d72236469be58b07aabf76cfb4e3f42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80c6e57bbbbd9df283eceee82b5e3a29bb32d5dd0b6a458cedeb47707b95d74f5e76f64d8e4dc0cbd78aa7cdfa639c90f3e30eaff41c44586c240879048abbff"} 13:13:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:13:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:13:16 INFO - Contents: 13:13:16 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b25d2a510847fe9e112f0130f64c1a049c6a1ae546802e2eaf7e74906f7379ef65f6be13d7c1ab8568f5ffca1e0371434c2b3e42f61e2606fd750048cfd47ee", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f196521b5192e9b0d5d44f2178e23b94c78f270aff693c061c06cb69ffa1b79c5613b36cf0e7230fd3e5bcd8b3ca20668c8037d6513a1ca52382127b5a739e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/510bb877d3d49f8237c11c9e6e77809a46a11b3e79ce90c7718c83942642dd6cb59931e69c0ce33bd69b5c7ee3ea1c728d72236469be58b07aabf76cfb4e3f42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80c6e57bbbbd9df283eceee82b5e3a29bb32d5dd0b6a458cedeb47707b95d74f5e76f64d8e4dc0cbd78aa7cdfa639c90f3e30eaff41c44586c240879048abbff"} 13:13:16 INFO - Running post-run listener: copy_logs_to_upload_dir 13:13:16 INFO - Copying logs to upload dir... 13:13:16 INFO - mkdir: /builds/slave/test/build/upload/logs 13:13:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.565715 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-03-30 13:13:15.673028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 13:13:15.676535) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b25d2a510847fe9e112f0130f64c1a049c6a1ae546802e2eaf7e74906f7379ef65f6be13d7c1ab8568f5ffca1e0371434c2b3e42f61e2606fd750048cfd47ee", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f196521b5192e9b0d5d44f2178e23b94c78f270aff693c061c06cb69ffa1b79c5613b36cf0e7230fd3e5bcd8b3ca20668c8037d6513a1ca52382127b5a739e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/510bb877d3d49f8237c11c9e6e77809a46a11b3e79ce90c7718c83942642dd6cb59931e69c0ce33bd69b5c7ee3ea1c728d72236469be58b07aabf76cfb4e3f42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80c6e57bbbbd9df283eceee82b5e3a29bb32d5dd0b6a458cedeb47707b95d74f5e76f64d8e4dc0cbd78aa7cdfa639c90f3e30eaff41c44586c240879048abbff"} build_url:https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/PvPBgcrATKe9jbeDrVR4iQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0b25d2a510847fe9e112f0130f64c1a049c6a1ae546802e2eaf7e74906f7379ef65f6be13d7c1ab8568f5ffca1e0371434c2b3e42f61e2606fd750048cfd47ee", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f196521b5192e9b0d5d44f2178e23b94c78f270aff693c061c06cb69ffa1b79c5613b36cf0e7230fd3e5bcd8b3ca20668c8037d6513a1ca52382127b5a739e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/510bb877d3d49f8237c11c9e6e77809a46a11b3e79ce90c7718c83942642dd6cb59931e69c0ce33bd69b5c7ee3ea1c728d72236469be58b07aabf76cfb4e3f42", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/80c6e57bbbbd9df283eceee82b5e3a29bb32d5dd0b6a458cedeb47707b95d74f5e76f64d8e4dc0cbd78aa7cdfa639c90f3e30eaff41c44586c240879048abbff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 13:13:15.706885) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:13:15.707218) ========= 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=ba164b0a7b0ec7655860e956000001c1-1490903329.760899-1246514339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 13:13:15.779939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 13:13:15.780259) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 13:13:15.780556) ========= ========= Total master_lag: 0.29 =========