builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-040 starttime: 1487347841.46 results: success (0) revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.460382) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.460815) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.461084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.505245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.505674) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 08:10:41-- 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% 46.6M=0s 2017-02-17 08:10:41 (46.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143582 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.671571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.671875) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022776 ========= master_lag: 0.15 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.842842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:41.843211) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev df9a0acc26486790c8794b787c764654d32c3f9c --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 df9a0acc26486790c8794b787c764654d32c3f9c --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=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 08:10:42,014 truncating revision to first 12 chars 2017-02-17 08:10:42,014 Setting DEBUG logging. 2017-02-17 08:10:42,014 attempt 1/10 2017-02-17 08:10:42,014 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/df9a0acc2648?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 08:10:42,474 unpacking tar archive at: mozilla-central-df9a0acc2648/testing/mozharness/ program finished with exit code 0 elapsedTime=0.752328 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:42.608280) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:42.608592) ========= script_repo_revision: df9a0acc26486790c8794b787c764654d32c3f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:42.608955) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:42.609213) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 08:10:42.619740) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 08:10:42.620036) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:10:42 INFO - MultiFileLogger online at 20170217 08:10:42 in /builds/slave/test 08:10:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:10:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:10:42 INFO - {'append_to_log': False, 08:10:42 INFO - 'base_work_dir': '/builds/slave/test', 08:10:42 INFO - 'blob_upload_branch': 'Firefox', 08:10:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:10:42 INFO - 'branch': 'Firefox', 08:10:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:42 INFO - 'config_files': ('talos/linux_config.py',), 08:10:42 INFO - 'default_actions': ('clobber', 08:10:42 INFO - 'read-buildbot-config', 08:10:42 INFO - 'download-and-extract', 08:10:42 INFO - 'populate-webroot', 08:10:42 INFO - 'create-virtualenv', 08:10:42 INFO - 'install', 08:10:42 INFO - 'run-tests'), 08:10:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:42 INFO - 'download_minidump_stackwalk': True, 08:10:42 INFO - 'download_symbols': 'ondemand', 08:10:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:42 INFO - 'installer_path': 'installer.exe', 08:10:42 INFO - 'log_level': 'info', 08:10:42 INFO - 'log_name': 'talos', 08:10:42 INFO - 'log_to_console': True, 08:10:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:10:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:10:42 INFO - 'opt_config_files': (), 08:10:42 INFO - 'pip_index': False, 08:10:42 INFO - 'sps_profile': False, 08:10:42 INFO - 'sps_profile_interval': 0, 08:10:42 INFO - 'suite': 'dromaeojs', 08:10:42 INFO - 'system_bits': '32', 08:10:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:42 INFO - 'title': 'talos-linux64-ix-040', 08:10:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:42 INFO - 'use_talos_json': True, 08:10:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:10:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:42 INFO - 'work_dir': 'build'} 08:10:42 INFO - [mozharness: 2017-02-17 16:10:42.895515Z] Running clobber step. 08:10:42 INFO - Running pre-action listener: _resource_record_pre_action 08:10:42 INFO - Running main action method: clobber 08:10:42 INFO - rmtree: /builds/slave/test/build 08:10:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:10:43 INFO - Running post-action listener: _resource_record_post_action 08:10:43 INFO - [mozharness: 2017-02-17 16:10:43.814621Z] Finished clobber step (success) 08:10:43 INFO - [mozharness: 2017-02-17 16:10:43.814722Z] Running read-buildbot-config step. 08:10:43 INFO - Running pre-action listener: _resource_record_pre_action 08:10:43 INFO - Running main action method: read_buildbot_config 08:10:43 INFO - Using buildbot properties: 08:10:43 INFO - { 08:10:43 INFO - "project": "", 08:10:43 INFO - "product": "firefox", 08:10:43 INFO - "who": "ffxbld@noreply.mozilla.org", 08:10:43 INFO - "installer_path": "public/build/target.tar.bz2", 08:10:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:10:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:10:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:10:43 INFO - "stage_platform": "linux64-pgo", 08:10:43 INFO - "basedir": "/builds/slave/test", 08:10:43 INFO - "buildnumber": 38, 08:10:43 INFO - "platform": "ubuntu64_hw", 08:10:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:10:43 INFO - "slavebuilddir": "test", 08:10:43 INFO - "taskId": "Ij04OZckS7--THnKgV6GfQ", 08:10:43 INFO - "branch": "mozilla-central", 08:10:43 INFO - "script_repo_revision": "production", 08:10:43 INFO - "revision": "df9a0acc26486790c8794b787c764654d32c3f9c", 08:10:43 INFO - "slavename": "talos-linux64-ix-040", 08:10:43 INFO - "repo_path": "mozilla-central" 08:10:43 INFO - } 08:10:43 INFO - Finding installer, test and symbols from parent task. 08:10:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ij04OZckS7--THnKgV6GfQ'}, attempt #1 08:10:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ij04OZckS7--THnKgV6GfQ'}, attempt #1 08:10:44 INFO - Task dependencies: R4PvaOweSLmak8_VvaO9vg 08:10:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg'}, attempt #1 08:10:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 08:10:45 INFO - Set test_url: https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/test_packages.json 08:10:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.crashreporter-symbols.zip 08:10:45 INFO - Running post-action listener: _resource_record_post_action 08:10:45 INFO - [mozharness: 2017-02-17 16:10:45.217432Z] Finished read-buildbot-config step (success) 08:10:45 INFO - [mozharness: 2017-02-17 16:10:45.217693Z] Running download-and-extract step. 08:10:45 INFO - Running pre-action listener: _resource_record_pre_action 08:10:45 INFO - Running main action method: download_and_extract 08:10:45 INFO - mkdir: /builds/slave/test/build/tests 08:10: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')]} 08:10:45 INFO - trying https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.test_packages.json 08:10:45 INFO - Downloading https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:10:45 INFO - Downloaded 896 bytes. 08:10:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:10:45 INFO - Using the following test package requirements: 08:10:45 INFO - {u'common': [u'target.common.tests.zip'], 08:10:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:10:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:10:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:10:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:10:45 INFO - u'mozbase': [u'target.common.tests.zip'], 08:10:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:10:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:10:45 INFO - u'web-platform': [u'target.common.tests.zip', 08:10:45 INFO - u'target.web-platform.tests.zip'], 08:10:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:10:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip 08:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip into memory 08:10:46 INFO - Expected file size: 59052557 08:10:46 INFO - Obtained file size: 59052557 08:10:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:10:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip 08:10:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:10:56 INFO - Fetch https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.common.tests.zip into memory 08:10:57 INFO - Expected file size: 59052557 08:10:57 INFO - Obtained file size: 59052557 08:11:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.talos.tests.zip 08:11:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:11:08 INFO - Fetch https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.talos.tests.zip into memory 08:11:08 INFO - Expected file size: 14403647 08:11:08 INFO - Obtained file size: 14403647 08:11:09 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')]} 08:11:09 INFO - trying https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 08:11:09 INFO - Downloading https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:11:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:11:10 INFO - Downloaded 66767565 bytes. 08:11:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 08:11:10 INFO - mkdir: /builds/slave/test/properties 08:11:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:10 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:10 INFO - Contents: 08:11:10 INFO - build_url:https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 08:11:10 INFO - Running post-action listener: _resource_record_post_action 08:11:10 INFO - Running post-action listener: set_extra_try_arguments 08:11:10 INFO - [mozharness: 2017-02-17 16:11:10.357260Z] Finished download-and-extract step (success) 08:11:10 INFO - [mozharness: 2017-02-17 16:11:10.357356Z] Running populate-webroot step. 08:11:10 INFO - Running pre-action listener: _resource_record_pre_action 08:11:10 INFO - Running main action method: populate_webroot 08:11:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:10 INFO - '--tppagecycles', 08:11:10 INFO - '7'], 08:11:10 INFO - 'tests': ['tsvgm']}}, 08:11:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['damp', 'tps']}, 08:11:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'tests': ['damp', 'tps']}, 08:11:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['dromaeo_dom']}, 08:11:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['basic_compositor_video']}, 08:11:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['a11yr', 08:11:10 INFO - 'ts_paint', 08:11:10 INFO - 'tpaint', 08:11:10 INFO - 'sessionrestore', 08:11:10 INFO - 'sessionrestore_no_auto_restore', 08:11:10 INFO - 'tabpaint']}, 08:11:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['tsvgx', 08:11:10 INFO - 'tsvgr_opacity', 08:11:10 INFO - 'tart', 08:11:10 INFO - 'tscrollx', 08:11:10 INFO - 'cart', 08:11:10 INFO - 'tsvg_static']}, 08:11:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:10 INFO - 'tsvgr_opacity', 08:11:10 INFO - 'tart', 08:11:10 INFO - 'tscrollx', 08:11:10 INFO - 'cart', 08:11:10 INFO - 'tsvg_static']}, 08:11:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'talos_options': ['--disable-e10s'], 08:11:10 INFO - 'tests': ['tp5o']}, 08:11:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:11:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'talos_options': ['--disable-e10s', 08:11:10 INFO - '--xperf_path', 08:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:10 INFO - 'tests': ['tp5n']}, 08:11:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:11:10 INFO - 'talos_options': ['--xperf_path', 08:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:10 INFO - 'tests': ['tp5n']}}, 08:11:10 INFO - 'talos.zip': {'path': '', 08:11:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:10 INFO - Running post-action listener: _resource_record_post_action 08:11:10 INFO - [mozharness: 2017-02-17 16:11:10.370421Z] Finished populate-webroot step (success) 08:11:10 INFO - [mozharness: 2017-02-17 16:11:10.370515Z] Running create-virtualenv step. 08:11:10 INFO - Running pre-action listener: _resource_record_pre_action 08:11:10 INFO - Running main action method: create_virtualenv 08:11:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:10 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 08:11:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:10 INFO - Using real prefix '/usr' 08:11:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:12 INFO - Installing pip.................done. 08:11:12 INFO - Return code: 0 08:11:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:11:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:11:13 INFO - Reading from file tmpfile_stdout 08:11:13 INFO - Output received: 08:11:13 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) 08:11:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:13 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')]} 08:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:13 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')]} 08:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:13 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:13 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 08:11:13 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 08:11:13 INFO - Using env: {'DISPLAY': ':0', 08:11:13 INFO - 'HOME': '/home/cltbld', 08:11:13 INFO - 'LANG': 'en_US.UTF-8', 08:11:13 INFO - 'LANGUAGE': 'en_US:en', 08:11:13 INFO - 'LOGNAME': 'cltbld', 08:11:13 INFO - 'MAIL': '/var/mail/cltbld', 08:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:13 INFO - 'MOZ_NO_REMOTE': '1', 08:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:13 INFO - 'NO_EM_RESTART': '1', 08:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:13 INFO - 'PWD': '/builds/slave/test', 08:11:13 INFO - 'SHELL': '/bin/bash', 08:11:13 INFO - 'SHLVL': '1', 08:11:13 INFO - 'TERM': 'linux', 08:11:13 INFO - 'TMOUT': '86400', 08:11:13 INFO - 'USER': 'cltbld', 08:11:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:13 INFO - '_': '/tools/buildbot/bin/python'} 08:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:13 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 08:11:13 INFO - Cleaning up... 08:11:13 INFO - Return code: 0 08:11:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:13 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')]} 08:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:13 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')]} 08:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:13 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:13 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 08:11:13 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 08:11:13 INFO - Using env: {'DISPLAY': ':0', 08:11:13 INFO - 'HOME': '/home/cltbld', 08:11:13 INFO - 'LANG': 'en_US.UTF-8', 08:11:13 INFO - 'LANGUAGE': 'en_US:en', 08:11:13 INFO - 'LOGNAME': 'cltbld', 08:11:13 INFO - 'MAIL': '/var/mail/cltbld', 08:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:13 INFO - 'MOZ_NO_REMOTE': '1', 08:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:13 INFO - 'NO_EM_RESTART': '1', 08:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:13 INFO - 'PWD': '/builds/slave/test', 08:11:13 INFO - 'SHELL': '/bin/bash', 08:11:13 INFO - 'SHLVL': '1', 08:11:13 INFO - 'TERM': 'linux', 08:11:13 INFO - 'TMOUT': '86400', 08:11:13 INFO - 'USER': 'cltbld', 08:11:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:13 INFO - '_': '/tools/buildbot/bin/python'} 08:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:13 INFO - Downloading/unpacking psutil>=3.1.1 08:11:13 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 08:11:13 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 08:11:13 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 08:11:13 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 08:11:13 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 08:11:13 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 08:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:15 INFO - Installing collected packages: psutil 08:11:15 INFO - Running setup.py install for psutil 08:11:15 INFO - building 'psutil._psutil_linux' extension 08:11:15 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 08:11:15 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 08:11:15 INFO - building 'psutil._psutil_posix' extension 08:11:15 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 08:11:16 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 08:11:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:16 INFO - Successfully installed psutil 08:11:16 INFO - Cleaning up... 08:11:16 INFO - Return code: 0 08:11:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11:16 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')]} 08:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:16 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')]} 08:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:16 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:16 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 08:11:16 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 08:11:16 INFO - Using env: {'DISPLAY': ':0', 08:11:16 INFO - 'HOME': '/home/cltbld', 08:11:16 INFO - 'LANG': 'en_US.UTF-8', 08:11:16 INFO - 'LANGUAGE': 'en_US:en', 08:11:16 INFO - 'LOGNAME': 'cltbld', 08:11:16 INFO - 'MAIL': '/var/mail/cltbld', 08:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:16 INFO - 'MOZ_NO_REMOTE': '1', 08:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:16 INFO - 'NO_EM_RESTART': '1', 08:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:16 INFO - 'PWD': '/builds/slave/test', 08:11:16 INFO - 'SHELL': '/bin/bash', 08:11:16 INFO - 'SHLVL': '1', 08:11:16 INFO - 'TERM': 'linux', 08:11:16 INFO - 'TMOUT': '86400', 08:11:16 INFO - 'USER': 'cltbld', 08:11:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:16 INFO - '_': '/tools/buildbot/bin/python'} 08:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11:16 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 08:11:16 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 08:11:16 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 08:11:16 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 08:11:16 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 08:11:16 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 08:11:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:11:18 INFO - Installing collected packages: mozsystemmonitor 08:11:18 INFO - Running setup.py install for mozsystemmonitor 08:11:19 INFO - Successfully installed mozsystemmonitor 08:11:19 INFO - Cleaning up... 08:11:19 INFO - Return code: 0 08:11:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:11:19 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')]} 08:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:19 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')]} 08:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:19 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:19 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 08:11:19 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 08:11:19 INFO - Using env: {'DISPLAY': ':0', 08:11:19 INFO - 'HOME': '/home/cltbld', 08:11:19 INFO - 'LANG': 'en_US.UTF-8', 08:11:19 INFO - 'LANGUAGE': 'en_US:en', 08:11:19 INFO - 'LOGNAME': 'cltbld', 08:11:19 INFO - 'MAIL': '/var/mail/cltbld', 08:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:19 INFO - 'MOZ_NO_REMOTE': '1', 08:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:19 INFO - 'NO_EM_RESTART': '1', 08:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:19 INFO - 'PWD': '/builds/slave/test', 08:11:19 INFO - 'SHELL': '/bin/bash', 08:11:19 INFO - 'SHLVL': '1', 08:11:19 INFO - 'TERM': 'linux', 08:11:19 INFO - 'TMOUT': '86400', 08:11:19 INFO - 'USER': 'cltbld', 08:11:19 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:19 INFO - '_': '/tools/buildbot/bin/python'} 08:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:19 INFO - Downloading/unpacking jsonschema==2.5.1 08:11:19 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 08:11:19 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 08:11:19 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 08:11:19 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 08:11:19 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 08:11:19 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 08:11:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:11:21 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 08:11:21 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 08:11:21 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 08:11:21 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 08:11:21 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 08:11:21 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 08:11:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:21 INFO - warning: no files found matching '*.txt' 08:11:21 INFO - no previously-included directories found matching 'build' 08:11:21 INFO - no previously-included directories found matching 'dist' 08:11:21 INFO - no previously-included directories found matching '.git*' 08:11:21 INFO - Installing collected packages: jsonschema, functools32 08:11:21 INFO - Running setup.py install for functools32 08:11:21 INFO - warning: no files found matching '*.txt' 08:11:21 INFO - no previously-included directories found matching 'build' 08:11:21 INFO - no previously-included directories found matching 'dist' 08:11:21 INFO - no previously-included directories found matching '.git*' 08:11:21 INFO - Successfully installed jsonschema functools32 08:11:21 INFO - Cleaning up... 08:11:21 INFO - Return code: 0 08:11:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:21 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:21 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 08:11:21 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 08:11:21 INFO - Using env: {'DISPLAY': ':0', 08:11:21 INFO - 'HOME': '/home/cltbld', 08:11:21 INFO - 'LANG': 'en_US.UTF-8', 08:11:21 INFO - 'LANGUAGE': 'en_US:en', 08:11:21 INFO - 'LOGNAME': 'cltbld', 08:11:21 INFO - 'MAIL': '/var/mail/cltbld', 08:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:21 INFO - 'MOZ_NO_REMOTE': '1', 08:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:21 INFO - 'NO_EM_RESTART': '1', 08:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:21 INFO - 'PWD': '/builds/slave/test', 08:11:21 INFO - 'SHELL': '/bin/bash', 08:11:21 INFO - 'SHLVL': '1', 08:11:21 INFO - 'TERM': 'linux', 08:11:21 INFO - 'TMOUT': '86400', 08:11:21 INFO - 'USER': 'cltbld', 08:11:21 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:21 INFO - '_': '/tools/buildbot/bin/python'} 08:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:11:21 INFO - Cleaning up... 08:11:22 INFO - Return code: 0 08:11:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:11:22 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')]} 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 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')]} 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:22 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 08:11:22 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 08:11:22 INFO - Using env: {'DISPLAY': ':0', 08:11:22 INFO - 'HOME': '/home/cltbld', 08:11:22 INFO - 'LANG': 'en_US.UTF-8', 08:11:22 INFO - 'LANGUAGE': 'en_US:en', 08:11:22 INFO - 'LOGNAME': 'cltbld', 08:11:22 INFO - 'MAIL': '/var/mail/cltbld', 08:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:22 INFO - 'MOZ_NO_REMOTE': '1', 08:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:22 INFO - 'NO_EM_RESTART': '1', 08:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:22 INFO - 'PWD': '/builds/slave/test', 08:11:22 INFO - 'SHELL': '/bin/bash', 08:11:22 INFO - 'SHLVL': '1', 08:11:22 INFO - 'TERM': 'linux', 08:11:22 INFO - 'TMOUT': '86400', 08:11:22 INFO - 'USER': 'cltbld', 08:11:22 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:22 INFO - '_': '/tools/buildbot/bin/python'} 08:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:22 INFO - Downloading/unpacking blobuploader==1.2.4 08:11:22 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 08:11:22 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 08:11:22 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 08:11:22 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 08:11:22 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 08:11:22 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 08:11:24 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:11:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 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 08:11:24 INFO - Downloading docopt-0.6.1.tar.gz 08:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:11:25 INFO - Installing collected packages: blobuploader, requests, docopt 08:11:25 INFO - Running setup.py install for blobuploader 08:11:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:11:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:11:25 INFO - Running setup.py install for requests 08:11:25 INFO - Running setup.py install for docopt 08:11:25 INFO - Successfully installed blobuploader requests docopt 08:11:25 INFO - Cleaning up... 08:11:25 INFO - Return code: 0 08:11:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:25 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')]} 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:25 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')]} 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:25 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:25 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 08:11:25 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 08:11:25 INFO - Using env: {'DISPLAY': ':0', 08:11:25 INFO - 'HOME': '/home/cltbld', 08:11:25 INFO - 'LANG': 'en_US.UTF-8', 08:11:25 INFO - 'LANGUAGE': 'en_US:en', 08:11:25 INFO - 'LOGNAME': 'cltbld', 08:11:25 INFO - 'MAIL': '/var/mail/cltbld', 08:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:25 INFO - 'MOZ_NO_REMOTE': '1', 08:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:25 INFO - 'NO_EM_RESTART': '1', 08:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:25 INFO - 'PWD': '/builds/slave/test', 08:11:25 INFO - 'SHELL': '/bin/bash', 08:11:25 INFO - 'SHLVL': '1', 08:11:25 INFO - 'TERM': 'linux', 08:11:25 INFO - 'TMOUT': '86400', 08:11:25 INFO - 'USER': 'cltbld', 08:11:25 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:25 INFO - '_': '/tools/buildbot/bin/python'} 08:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:25 INFO - Running setup.py (path:/tmp/pip-oQBFNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:25 INFO - Running setup.py (path:/tmp/pip-PSNNC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:26 INFO - Running setup.py (path:/tmp/pip-t74Bqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:26 INFO - Running setup.py (path:/tmp/pip-gltFcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:26 INFO - Running setup.py (path:/tmp/pip-AQbnxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:26 INFO - Running setup.py (path:/tmp/pip-MEbfgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:26 INFO - Running setup.py (path:/tmp/pip-BUWHCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:26 INFO - Running setup.py (path:/tmp/pip-VezbKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:26 INFO - Running setup.py (path:/tmp/pip-aSZYgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:26 INFO - Running setup.py (path:/tmp/pip-5mScwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:26 INFO - Running setup.py (path:/tmp/pip-URD88c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:26 INFO - Running setup.py (path:/tmp/pip-HHUnRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:26 INFO - Running setup.py (path:/tmp/pip-y2n3rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:26 INFO - Running setup.py (path:/tmp/pip-0zQDUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:26 INFO - Running setup.py (path:/tmp/pip-IuKnia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:26 INFO - Running setup.py (path:/tmp/pip-gUBd3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:27 INFO - Running setup.py (path:/tmp/pip-TJomaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:27 INFO - Running setup.py install for manifestparser 08:11:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Running setup.py install for mozcrash 08:11:27 INFO - Running setup.py install for mozdebug 08:11:27 INFO - Running setup.py install for mozdevice 08:11:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Running setup.py install for mozfile 08:11:27 INFO - Running setup.py install for mozhttpd 08:11:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Running setup.py install for mozinfo 08:11:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Running setup.py install for mozInstall 08:11:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:27 INFO - Running setup.py install for mozleak 08:11:28 INFO - Running setup.py install for mozlog 08:11:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for moznetwork 08:11:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozprocess 08:11:28 INFO - Running setup.py install for mozprofile 08:11:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozrunner 08:11:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozscreenshot 08:11:28 INFO - Running setup.py install for moztest 08:11:28 INFO - Running setup.py install for mozversion 08:11:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:28 INFO - Cleaning up... 08:11:28 INFO - Return code: 0 08:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:28 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:28 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 08:11:28 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 08:11:28 INFO - Using env: {'DISPLAY': ':0', 08:11:28 INFO - 'HOME': '/home/cltbld', 08:11:28 INFO - 'LANG': 'en_US.UTF-8', 08:11:28 INFO - 'LANGUAGE': 'en_US:en', 08:11:28 INFO - 'LOGNAME': 'cltbld', 08:11:28 INFO - 'MAIL': '/var/mail/cltbld', 08:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:28 INFO - 'MOZ_NO_REMOTE': '1', 08:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:28 INFO - 'NO_EM_RESTART': '1', 08:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:28 INFO - 'PWD': '/builds/slave/test', 08:11:28 INFO - 'SHELL': '/bin/bash', 08:11:28 INFO - 'SHLVL': '1', 08:11:28 INFO - 'TERM': 'linux', 08:11:28 INFO - 'TMOUT': '86400', 08:11:28 INFO - 'USER': 'cltbld', 08:11:28 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:28 INFO - '_': '/tools/buildbot/bin/python'} 08:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:29 INFO - Running setup.py (path:/tmp/pip-ZFBF1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:29 INFO - Running setup.py (path:/tmp/pip-kbygQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:29 INFO - Running setup.py (path:/tmp/pip-gMc21C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:29 INFO - Running setup.py (path:/tmp/pip-OxxKG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:29 INFO - Running setup.py (path:/tmp/pip-QQiIPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:29 INFO - Running setup.py (path:/tmp/pip-rE9KHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:29 INFO - Running setup.py (path:/tmp/pip-b3fzzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:29 INFO - Running setup.py (path:/tmp/pip-3KFeYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:29 INFO - Running setup.py (path:/tmp/pip-fce2yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:29 INFO - Running setup.py (path:/tmp/pip-V39yqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:29 INFO - Running setup.py (path:/tmp/pip-qnug1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:29 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)) 08:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:29 INFO - Running setup.py (path:/tmp/pip-D_RhRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:30 INFO - Running setup.py (path:/tmp/pip-30sFl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:30 INFO - Running setup.py (path:/tmp/pip-takkKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:30 INFO - Running setup.py (path:/tmp/pip-unpwwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:30 INFO - Running setup.py (path:/tmp/pip-r8yMhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:30 INFO - Running setup.py (path:/tmp/pip-fb8yfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:30 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)) 08:11:30 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)) 08:11:30 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)) 08:11:30 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)) 08:11:30 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)) 08:11:30 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)) 08:11:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:30 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 08:11:30 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 08:11:30 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 08:11:30 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 08:11:30 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 08:11:30 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 08:11:32 INFO - Downloading blessings-1.6.tar.gz 08:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:32 INFO - Installing collected packages: blessings 08:11:32 INFO - Running setup.py install for blessings 08:11:32 INFO - Successfully installed blessings 08:11:32 INFO - Cleaning up... 08:11:32 INFO - Return code: 0 08:11:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:32 INFO - Reading from file tmpfile_stdout 08:11:32 INFO - Current package versions: 08:11:32 INFO - argparse == 1.2.1 08:11:32 INFO - blessings == 1.6 08:11:32 INFO - blobuploader == 1.2.4 08:11:32 INFO - docopt == 0.6.1 08:11:32 INFO - functools32 == 3.2.3-2 08:11:32 INFO - jsonschema == 2.5.1 08:11:32 INFO - manifestparser == 1.1 08:11:32 INFO - mozInstall == 1.12 08:11:32 INFO - mozcrash == 1.0 08:11:32 INFO - mozdebug == 0.1 08:11:32 INFO - mozdevice == 0.49 08:11:32 INFO - mozfile == 1.2 08:11:32 INFO - mozhttpd == 0.7 08:11:32 INFO - mozinfo == 0.9 08:11:32 INFO - mozleak == 0.1 08:11:32 INFO - mozlog == 3.4 08:11:32 INFO - moznetwork == 0.27 08:11:32 INFO - mozprocess == 0.24 08:11:32 INFO - mozprofile == 0.28 08:11:32 INFO - mozrunner == 6.13 08:11:32 INFO - mozscreenshot == 0.1 08:11:32 INFO - mozsystemmonitor == 0.3 08:11:32 INFO - moztest == 0.8 08:11:32 INFO - mozversion == 1.4 08:11:32 INFO - psutil == 3.1.1 08:11:32 INFO - requests == 1.2.3 08:11:32 INFO - wsgiref == 0.1.2 08:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:32 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')]} 08:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:32 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')]} 08:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:32 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:32 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 08:11:32 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 08:11:32 INFO - Using env: {'DISPLAY': ':0', 08:11:32 INFO - 'HOME': '/home/cltbld', 08:11:32 INFO - 'LANG': 'en_US.UTF-8', 08:11:32 INFO - 'LANGUAGE': 'en_US:en', 08:11:32 INFO - 'LOGNAME': 'cltbld', 08:11:32 INFO - 'MAIL': '/var/mail/cltbld', 08:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:32 INFO - 'MOZ_NO_REMOTE': '1', 08:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:32 INFO - 'NO_EM_RESTART': '1', 08:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:32 INFO - 'PWD': '/builds/slave/test', 08:11:32 INFO - 'SHELL': '/bin/bash', 08:11:32 INFO - 'SHLVL': '1', 08:11:32 INFO - 'TERM': 'linux', 08:11:32 INFO - 'TMOUT': '86400', 08:11:32 INFO - 'USER': 'cltbld', 08:11:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:32 INFO - '_': '/tools/buildbot/bin/python'} 08:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 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)) 08:11:33 INFO - Cleaning up... 08:11:33 INFO - Return code: 0 08:11:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:33 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 0xece1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x116eed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, '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': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', '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 08:11:33 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 08:11:33 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 08:11:33 INFO - Using env: {'DISPLAY': ':0', 08:11:33 INFO - 'HOME': '/home/cltbld', 08:11:33 INFO - 'LANG': 'en_US.UTF-8', 08:11:33 INFO - 'LANGUAGE': 'en_US:en', 08:11:33 INFO - 'LOGNAME': 'cltbld', 08:11:33 INFO - 'MAIL': '/var/mail/cltbld', 08:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:33 INFO - 'MOZ_NO_REMOTE': '1', 08:11:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:33 INFO - 'NO_EM_RESTART': '1', 08:11:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:33 INFO - 'PWD': '/builds/slave/test', 08:11:33 INFO - 'SHELL': '/bin/bash', 08:11:33 INFO - 'SHLVL': '1', 08:11:33 INFO - 'TERM': 'linux', 08:11:33 INFO - 'TMOUT': '86400', 08:11:33 INFO - 'USER': 'cltbld', 08:11:33 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:33 INFO - '_': '/tools/buildbot/bin/python'} 08:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:33 INFO - Cleaning up... 08:11:33 INFO - Return code: 0 08:11:33 INFO - Running post-action listener: _resource_record_post_action 08:11:33 INFO - Running post-action listener: _start_resource_monitoring 08:11:33 INFO - Starting resource monitoring. 08:11:33 INFO - [mozharness: 2017-02-17 16:11:33.361523Z] Finished create-virtualenv step (success) 08:11:33 INFO - [mozharness: 2017-02-17 16:11:33.362105Z] Running install step. 08:11:33 INFO - Running pre-action listener: _resource_record_pre_action 08:11:33 INFO - Running main action method: install 08:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:33 INFO - Reading from file tmpfile_stdout 08:11:33 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:33 INFO - Reading from file tmpfile_stdout 08:11:33 INFO - Output received: 08:11:33 INFO - Usage: mozinstall [options] installer 08:11:33 INFO - Options: 08:11:33 INFO - -h, --help show this help message and exit 08:11:33 INFO - -d DEST, --destination=DEST 08:11:33 INFO - Directory to install application into. [default: 08:11:33 INFO - "/builds/slave/test"] 08:11:33 INFO - --app=APP Application being installed. [default: firefox] 08:11:33 INFO - mkdir: /builds/slave/test/build/application 08:11:33 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'] 08:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:11:50 INFO - Reading from file tmpfile_stdout 08:11:50 INFO - Output received: 08:11:50 INFO - /builds/slave/test/build/application/firefox/firefox 08:11:50 INFO - Running post-action listener: _resource_record_post_action 08:11:50 INFO - [mozharness: 2017-02-17 16:11:50.313620Z] Finished install step (success) 08:11:50 INFO - [mozharness: 2017-02-17 16:11:50.313898Z] Running run-tests step. 08:11:50 INFO - Running pre-action listener: _resource_record_pre_action 08:11:50 INFO - Running main action method: run_tests 08:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:50 INFO - Python 2.7.3 08:11:50 INFO - Return code: 0 08:11:50 INFO - grabbing minidump binary from tooltool 08:11: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')]} 08:11:50 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 0x11f8540>, '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 0x116cf70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x116d400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:50 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 08:11:50 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 08:11:50 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 08:11:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:50 INFO - Return code: 0 08:11:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:11:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:50 INFO - ENV: RUST_BACKTRACE is now 1 08:11:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:11:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:11:50 INFO - Using env: {'DISPLAY': ':0', 08:11:50 INFO - 'HOME': '/home/cltbld', 08:11:50 INFO - 'LANG': 'en_US.UTF-8', 08:11:50 INFO - 'LANGUAGE': 'en_US:en', 08:11:50 INFO - 'LOGNAME': 'cltbld', 08:11:50 INFO - 'MAIL': '/var/mail/cltbld', 08:11:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:50 INFO - 'MOZ_NO_REMOTE': '1', 08:11:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:50 INFO - 'NO_EM_RESTART': '1', 08:11:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:50 INFO - 'PWD': '/builds/slave/test', 08:11:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:50 INFO - 'RUST_BACKTRACE': '1', 08:11:50 INFO - 'SHELL': '/bin/bash', 08:11:50 INFO - 'SHLVL': '1', 08:11:50 INFO - 'TERM': 'linux', 08:11:50 INFO - 'TMOUT': '86400', 08:11:50 INFO - 'USER': 'cltbld', 08:11:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500', 08:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:50 INFO - '_': '/tools/buildbot/bin/python'} 08:11:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:11:50 INFO - mozversion application_buildid: 20170217150643 08:11:50 INFO - mozversion application_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 08:11:50 INFO - mozversion application_display_name: Nightly 08:11:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:50 INFO - mozversion application_name: Firefox 08:11:50 INFO - mozversion application_remotingname: firefox 08:11:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:11:50 INFO - mozversion application_vendor: Mozilla 08:11:50 INFO - mozversion application_version: 54.0a1 08:11:50 INFO - mozversion platform_buildid: 20170217150643 08:11:50 INFO - mozversion platform_changeset: df9a0acc26486790c8794b787c764654d32c3f9c 08:11:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:11:50 INFO - mozversion platform_version: 54.0a1 08:11:50 INFO - using testdate: 1487347910 08:11:50 INFO - actual date: 1487347910 08:11:50 INFO - starting webserver on 'localhost:45907' 08:11:50 INFO - SUITE-START | Running 2 tests 08:11:50 INFO - TEST-START | dromaeo_css 08:11:50 INFO - operating with platform_type : linux_ 08:11:50 INFO - Initialising browser for dromaeo_css test... 08:11:50 INFO - TEST-INFO | started process 31839 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHiRzgS/profile http://localhost:45907/getInfo.html) 08:11:53 INFO - PROCESS | 31839 | Iterating over profile /tmp/tmpHiRzgS/profile 08:11:54 INFO - PROCESS | 31839 | __metrics Screen width/height:1600/1200 08:11:54 INFO - PROCESS | 31839 | colorDepth:24 08:11:54 INFO - PROCESS | 31839 | Browser inner width/height: 1024/697 08:11:54 INFO - PROCESS | 31839 | __metrics 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/blocklist.xml = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/places.sqlite-shm = Fri Feb 17 2017 08:11:52 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/sessionCheckpoints.json = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/content-prefs.sqlite = Fri Feb 17 2017 08:11:52 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/safebrowsing = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/places.sqlite-wal = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/extensions.ini = Fri Feb 17 2017 08:11:51 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/extensions = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/pluginreg.dat = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/permissions.sqlite = Fri Feb 17 2017 08:11:51 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/gmp = Fri Feb 17 2017 08:11:51 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/secmod.db = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/prefs.js = Fri Feb 17 2017 08:11:08 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/addons.json = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/compatibility.ini = Fri Feb 17 2017 08:11:51 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/cookies.sqlite = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/storage.sqlite-journal = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/directoryLinks.json = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | Stat failure 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/mimeTypes.rdf = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | Stat failure 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/user.js = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/startupCache = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/minidumps = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/webappsstore.sqlite = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:54 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:54 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/storage.sqlite = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/thumbnails = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/key3.db = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/cert8.db = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/places.sqlite = Fri Feb 17 2017 08:11:54 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/extensions.json = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/crashes = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/localstore.rdf = Fri Feb 17 2017 08:11:08 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/bookmarkbackups = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/cache2 = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/.parentlock = Fri Feb 17 2017 08:11:50 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | No birth date. Using mtime. 08:11:55 INFO - PROCESS | 31839 | Using date: /tmp/tmpHiRzgS/profile/frequencyCap.json = Fri Feb 17 2017 08:11:53 GMT-0800 (PST) 08:11:55 INFO - PROCESS | 31839 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:11:55 INFO - PROCESS | 31839 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:56 INFO - TEST-INFO | 31839: exit 0 08:11:56 INFO - Browser initialized. 08:11:56 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:56 INFO - TEST-INFO | started process 31935 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHiRzgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:08 INFO - PROCESS | 31935 | RSS: Main: 142721024 08:12:08 INFO - PROCESS | 31935 | 08:15:10 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45907/tests/dromaeo/cssquery-ext.html) 08:15:11 INFO - PROCESS | 31935 | RSS: Main: 299200512 08:15:11 INFO - PROCESS | 31935 | 08:16:58 INFO - PROCESS | 31935 | ************************* 08:16:58 INFO - PROCESS | 31935 | A coding exception was thrown and uncaught in a Task. 08:16:58 INFO - PROCESS | 31935 | 08:16:58 ERROR - PROCESS | 31935 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:16:58 INFO - PROCESS | 31935 | Full stack: 08:16:58 INFO - PROCESS | 31935 | ************************* 08:18:14 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-ext.html (next: http://localhost:45907/tests/dromaeo/cssquery-jquery.html) 08:18:15 INFO - PROCESS | 31935 | RSS: Main: 244191232 08:18:15 INFO - PROCESS | 31935 | 08:21:17 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45907/tests/dromaeo/cssquery-mootools.html) 08:21:17 INFO - PROCESS | 31935 | RSS: Main: 213090304 08:21:17 INFO - PROCESS | 31935 | 08:24:19 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45907/tests/dromaeo/cssquery-prototype.html) 08:24:20 INFO - PROCESS | 31935 | RSS: Main: 179138560 08:24:20 INFO - PROCESS | 31935 | 08:27:23 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45907/tests/dromaeo/cssquery-yui.html) 08:27:23 INFO - PROCESS | 31935 | RSS: Main: 184356864 08:27:23 INFO - PROCESS | 31935 | 08:30:26 INFO - PROCESS | 31935 | Cycle 1(1): loaded http://localhost:45907/tests/dromaeo/cssquery-yui.html (next: http://localhost:45907/tests/dromaeo/cssquery-dojo.html) 08:30:26 INFO - PROCESS | 31935 | RSS: Main: 278589440 08:30:26 INFO - PROCESS | 31935 | 08:30:26 INFO - PROCESS | 31935 | __start_tp_report 08:30:26 INFO - PROCESS | 31935 | _x_x_mozilla_page_load 08:30:26 INFO - PROCESS | 31935 | _x_x_mozilla_page_load_details 08:30:26 INFO - PROCESS | 31935 | |i|pagename|runs| 08:30:26 INFO - PROCESS | 31935 | |0;dojo.html;3124.7500199984;3156.179393357729;3173.2225604726846;3175.333180032193;3183.4269831430347;5162.6128040397025;5205.60957928155;5259.579233661308;5288.603354748395;5352.357717073952;5194.37667479902;5212.113940630093;5213.400458947222;5231.555317797982;5251.947480525194;4416.80124394403;4612.262038073917;4842.249451335031;4864;4865.70805751656;4681.414823147106;4725.3148293497425;4806.0628177505405;4824.951750482503;4850.223964165734;4702.811887524529;4734.739589322586;4753.667243292971;4763.404574428214;4781.95218047822;4739.834105806315;4759.095771803346;4764.8332308369045;4839.612830973514;4843.15244832153;4736.384270044872;4741.57325840676;4751.619870410359;4758.881027974294;4773.11697335994;5159.071367153948;5193.195054766517;5293.153095504739;5297.602679799031;5330.38700549434;5658.405867383906;5663.348714897798;5670.8015219467125;5676.716164191774;5679.886402271932;5578.107502799533;5636.633618814805;5655.179998900177;5667.234923285386;5705.800296989627;5268.1834315681135;5315.495027972375;5374.435684253136;5376.1129413647095;5376.274202982587;5763.308402991668;5770.740316685759;5772.5670574707065;5772.942270577324;5794.739236734357;5452.345718513724;5488.615796894259;5496.0107180707055;5563.332400111932;5564.610477266633;5632.633878797865;5636.323641163087;5640.5487560995025;5640.915386269209;5644.068728659808;5611.915821262684;5614.438556144353;5637.238972738628;5653.491185793298;5775.306963164448;5746.02317606008;5787.797427090116;5787.913181302284;5795.594308398456;5801.12983052537;4451.710638808532;4456.799444025027;4459.264221403497;4461.174682683714;4500.324951257272;3906.1797022625;3977.960220397817;3982.143839074613;3993.740406873602;4063.1670507546473;5290.259363689087;5302.072137376021;5348.304720386325;5350.571954243651;5354.705491197944;5683.090705487102;5749.540036797046;5773.480386765212;5774.509166720792;5777.162311464898;5386.326709161355;5390.0836857734275;5392.56859451243;5398.13629819227;5436.565074794007;4913.140200464904;4915.65590408668;4919.188333924933;4925.270725813718;4961.603071754251;5625.831225063254;5676.233708449389;5680.573956953162;5733.827985160452;5922.555808314393;5540.279763630702;5548.250986116984;5625.46558076982;5644.887102258014;5721.570882183936;5826.70866456684;5876.6180198286975;5902.055671092605;5914.704264786657;5932.406759324033;5573.749181286771;5576.498115169654;5580.776768929198;5586.050371436947;5587.664740115606;5590.7763689453595;5621.269234999424;5682.602217844711;5686.061799803151;5691.174779657009;5445.455454454523;5501.449855014466;5517.2275881377445;5530.364008139116;5530.7511161997;5535.6955367455175;5536.031194541019;5546.251256080377;5561.304836895388;5588.413216612197;5480.945190548044;5548.639338443149;5552.139418390156;5569.55443564522;5603;5409.675419474844;5444.26502422168;5453.809116680897;5606.159076138611;5696.715164241854;5337.279467272024;5412.134058550613;5424.5660347172925;5437.374701909331;5446.1558458439 08:30:27 INFO - PROCESS | 31935 | |1;ext.html;537.760696490062;561.9859503512412;575.3441077172025;604.3503234023423;626.3548544998653;29578;30218.84890575547;41164.79417602915;43558.564414355846;43760.78119609405;44693.32960005594;44800.77599611998;45070.32394514085;45140;45162.32256516155;9093.863592046124;9273.95363023184;9290.860637090414;9308;9309.162175404212;7090.85818283634;7259.419246460297;9126.680566180185;10218.437985910772;10450.320729152581;10988.340699558012;11067.335959842396;11068.225224234307;11070.667879963614;11080.612178573752;10943.124550036018;11017.44912754367;11023.338599684044;11044.226904116715;11055.613053543168;10324.690259292232;10409.479526023668;10419.947900260526;10421.062104410563;10424.270301078928;41367.7931610343;41592.37611435831;41693.16613667709;41704.16591668149;41754;3796.0116968969437;3869;3877.631624995649;3909.6285852843935;3952.802359882023;31446.213844654067;31538.36923261545;31544.684553154406;31651.525227121612;32138.83930580332;4101.630853223194;4208.473940757405;4210.32634778437;4215;4332.761698106603;215853;215923;216288.99999999843;216900.83099168964;217335;54888.72555637276;54995;55313.1703024455;55330.17004744932;55340;50138.22028263868;51186.488135118176;56630.43369566252;58265;58273;14975.700485990219;15080.095194288378;15146;15164.469243576423;15183.089014659156;11210.831837522444;11318.264312819805;11325.263857849379;11428;11455.083593312514;15540.766888496682;15703.68592628164;15757.763633545506;15771;15797.289121989537;6279.466245369194;6306.180196574416;6374.681265936777;6434.163558737334;6486.481081513468;876.3602570123845;878.8399312907029;883.8497455432592;883.9071897450804;905.6558507767006;30061.63489944934;30238.84880575627;30915.536266956013;30977.44360902238;31204.687953120178;1014.4826138669332;1033.5142483033112;1037.7561273100816;1070.8072546941626;1199.3463562358536;15480.604393413758;16403.83596164047;16425.58936026585;16466.506004819876;16629.417970370978;405137;408781;409570;409625;410039;59892.70053649704;60147.69926150341;60248.39751602516;60270.39729602648;60280;1293.1142167615214;1333.3333333333333;1336.7593833109938;1354.3499120422055;1357.8234829472106;27945.301367465978;29182.56226156652;29599;30149;30188;36293;36430.8178459106;36523.26953460969;36524.26951461009;36556.45165322469;314880;316102;316831;317539;318347;37501.249975000894;37854.72871627711;38100.237995240495;38151.618483814804;38239.23521529498;43720.562794371646;43955.560444395145;44060.559394405645;44882.106241845286;44979.550204498846;34341.48487772735;34509.827450862584;35743.64256357507;36968.63031369652;37340.813295933345;35633.28733425265;36318.818405907805;36419.817900910326;36466.8176659115;36518;35306.64693353137;35618.46572301366;36394.636053640184;36702.44946325754;36973.81513092417;29167;31485.685143148276;31607.525887112173;31689.20776980594;31853.840730796197;24283.635745464184;24749.62875556832;24798.504029919663;25263.115790947228;25310.367240819127 08:30:27 INFO - PROCESS | 31935 | |2;jquery.html;364.41875209041604;378.7765218521072;379.62417206965097;393.82671624485243;397.63020391036343;13658.248796316211;14427;15993.280302386376;19633.214671413163;20896.477588060305;13615.046946713734;16905.40831070913;19137.808621913813;19548.70676939847;19668.508287292825;5473.480019398151;5659.377468478464;5660.29246344207;5664.348599911001;5665.830025099253;4652.511486293941;5505.256790333294;6177.042558403455;6207.193064901557;6316.399942005525;6232.252129744437;6315.842103947392;6322.272938612049;6328.303886572473;6330.493560515148;6761.594304341756;6818.863622727567;6823.863522729543;6850.177978642545;6854.794356169323;5521.751521181516;5738.6556806591325;5787.421257874179;6141.048137538689;6169.7840575580085;22581.435464113365;23296.65055024176;23479.7652023481;23674.64488032681;24024.759752402428;2619.122593931035;2649.337665583604;2691.1284492858313;2704.688960769517;2709.295583148376;9626.51867406634;9763.365381250196;9816.509174541316;10254.128399086087;10312.432816195107;1769.4780039888265;1842.6591080650123;1853.508820162658;1864.8787828791085;1867.766529183852;78853.60573197098;81222.9999999994;81393;88318.11681883164;88605.55697221472;11872.76254474923;11998.940005299917;12018.939905300593;12025.768790576127;12437.440315185839;17355.56611084733;17402.303907843798;17418;17422.912885435493;17427;7779.299863012356;7988.720394786154;8106.554139522265;8154.06228283755;8382.706605268908;7358.852822943618;7362.411007119417;7610.048743907012;7612;7664.386849052063;5091.236314552847;5142.331496905378;5223.164293713064;5275.894482110413;5337.546308563816;1929.5851391950798;1977.367242482392;1984.1468168687313;1992.8306093982173;2028.766691830429;841.7306461932245;864.6498168241872;874.2743522875998;880.9871829291312;887.1394747095308;19329.323473678636;20495.180192792122;20650.380488585368;20662.48343791387;20924.790752092285;871.5598622695499;874.8993865705526;878.7451639024739;887.4187407248263;896.762357975124;10863.83704244437;11450.713732156597;11483.598074067368;11508.53965841357;11515;595548;597209;599401.068877076;623202;629386;78034.60982695164;85740;101895;102117.48941255246;103307;995.6166875752888;1032.2670903658336;1049.1292227451197;1059.5496913811598;1059.9099076578577;17029.233684484476;17405.115285612654;17847.196876140344;17885.28458861631;18064.36774712879;33358.49962250617;40242.19515609613;44102.779486102365;45135.774321128185;46028;54534.727326363114;59647.70176149091;61236.387636123065;62253.37746622476;62341.37658623356;8157.265846073882;8390.958045209734;8462.619182136696;8685.435446695945;8963.327750418614;9175.678851240174;9233.522636378148;9953.353032052893;12907.161034532724;12943.223406595635;11304.943475282571;11506.94246528762;11533.423328833693;11641.708957276136;12007.219530730474;11162.665120046411;11189.72025699364;11237.269577477438;11515.884841151481;11774.411279435823;11695.590654327058;11871.703207419883;11986.64040078764;12173.513059477522;12208.267503949792;7739.110002349802;7745.419093568117;7947.483413578099;8489.95755021221;8506.362022848434;8759.605817738344;10919.56321747153;11700.70748231301;11737.88262117368;11932.940335298268 08:30:27 INFO - PROCESS | 31935 | |3;mootools.html;1212.448336007117;1394.7071115065835;1410.2737090398443;1428.4571862692176;1436.8994170408075;6866.279040700723;7607.619619019048;7957.562334071631;7975.401844861644;8139.186081391857;7939.841203175933;7994.92005079949;7996.200379962001;8005.279524842763;8006.67973281068;1817.9454616361531;2016.5261163626535;2047.2936836791282;2052.2098991888156;2052.363767232155;1846.639905218483;1864.2542982806917;2102.432343267317;2173.804357607815;2177.923772667957;2157.3635777445693;2157.9244726434576;2159.2442645074257;2166.2309879992613;2167.2089687264192;1961.7155512450686;1985.6326579582824;1986.3147214211074;1990.7710613279514;1996.8901710405921;2077.2106599492095;2114.619368513667;2142.4536743130557;2148.5702859428175;2151.9784802151935;8043.477173983731;8055.194480551956;8062.193780621949;8101;8113.350931925491;4732.716037037789;4839.395075615548;4845.515448455127;4852.4662287148385;4864.562189402971;3007.8195308281356;3148.071318960889;3192.1381227068796;3193.6486986431473;3198.984005079983;1214.7509760499165;1217.3730528777687;1234.0929416878678;1238.6717519857154;1245.9626211213588;10704.30422022566;10750.677479675622;10757.354558726502;10761.78476430467;10770.569177232901;3986.940195897064;4205.726627769185;4254.468191476066;4257.6168144867115;4294.935575966363;9255.305852061123;9260.907390926139;9261.814763704688;9266.166045055978;9271.443713377219;4969.552740253394;4983.401991761012;4985.576226020757;4985.800567977313;4986.202207646759;4333.111712099012;4508.098380323882;4529.5243999380245;4598.149342371672;4598.218302888517;3263.7225835803747;3297.7196938260035;3302.455094909313;3506.63180366063;3609.729270304685;1134.733337665648;1141.5662048421548;1169.2575214738702;1172.0682057764097;1179.9351035693119;813.7198336748744;833.3374966901328;836.9707060252862;850.2135524639807;857.7426771968385;4809.062232864558;4857.174280372275;4872.585830204402;4885.145099607555;5036.6726162799305;866.6490071521044;883.7348795361495;884.8983663798479;885.5660726244093;890.875277461156;3111.735502482269;3136.9215769605944;3140.9685903140676;3177.952330715041;3308.8180150091976;9182.540872956326;9481.09929556691;9488.620455181854;9497.762555936157;9523.904760952302;9472.621095156255;9480.288978326653;9481.09929556691;9485.098915603006;9488.383255088534;7247.565146091251;7309.232530584319;7408;7421.035265415461;7512.812179695442;4698.083873644743;4741.193997020584;4755.358026666356;4776.785044672929;4879.219324907997;8635;8644.740657780276;8651.048384677806;8657.61040753155;8745.912540874764;8339.291160251447;8638.654453821777;8670.739877803677;8747.212750852454;8888.155625215593;6827;6935.3411425914455;6954.687039083152;6955.965220173866;6958.373746362851;6533.281339052605;6762.087118238975;6992.545484543489;7157.964210178916;7236.493445458767;6561.278259391559;6584.736610535717;6701.128853249046;6739.123913891359;6788.76239331621;6549.181352330958;6567.179102612174;6592.077109204619;6612.437942774918;6632.933670663425;5502.504774570308;5504.532114770129;5543.805966791304;5584.664920104807;5604.159376093618;4486.349479325545;4553.408056952575;4606.239970404846;4634.976825115853;4699.624030077517;4371.191329604034;4414.0509790393935;4530.501644819002;4611.723296602215;4681.110588988081 08:30:27 INFO - PROCESS | 31935 | |4;prototype.html;379.99050023749413;388.0763782198367;389.6707282346416;393.5159753503189;397.376119492397;5178.5598224150945;5264.289320941672;5291.4708529147065;5302.1781623848265;5317.255584218213;5133.1786914093755;5146.639735218536;5222.373315202181;5264.710440925757;5298.1522956327;4158;4169.6455801256925;4170.4161417401665;4177.561356057616;4177.644900183488;4151.8962025949295;4198.622124008839;4210.263203939298;4210.663146948252;4217.15656868626;4000.2999475091888;4133.834646614132;4181.916361672765;4188.267053265681;4195.7063005589625;4104.363823607346;4107.856225032139;4124.525679546846;4133.979330103345;4149.211649786531;3715.5169827922196;3717.5724791648768;3723.087843478338;3726.366517691999;3728.8881333559766;5177.275181474596;5182.611304152204;5182.818601348972;5188.040212560688;5211.609129315317;4737.857128655808;4795.760211989386;4816.6764932196675;4861.781219845116;4903.8774030649165;5553.16702494625;5580.358258800249;5595.580331475115;5604.346717718283;5642.802501912454;4175.895602609929;4195.958040419618;4205.621494065549;4217.641500472463;4232.4286221360035;5555;5706.059939620507;5719.256496655408;5755.460391859018;5805.82582522525;4119.423280740699;4122.711410201317;4137.937930931039;4169.749815011049;4304.849330273488;4339.522652508222;4343.978280108579;4346.913061738747;4349.956500434955;4379.321205213197;4685.6017238535105;4883.316335713003;4941.975290123598;4943.036107958914;4956.801727930914;5550.778828657689;5655.802046928337;5683.062294721325;5685.129009849999;5685.772569097273;1482.4885414531968;1507.3819733909045;1510.4864346122372;1511.2972467802524;1513.341696362086;996.8405055191134;1050.3592808386877;1057.1489950589848;1070.6680928912062;1087.8903518411175;5240.266362709223;5273.156294992783;5273.630845840831;5274.393444753902;5287.836675931289;5562.554995600342;5577.6932268724795;5590.2453168821685;5637.703328234529;5679.716014199356;5108.259302401131;5304.09052741013;5333.466653334635;5411.404745477994;5441.755121019563;2998.661044385383;3001.5387382346266;3009.5554134015797;3015.0552893384083;3024.349764800578;5571.804986825442;5874.471297583238;5876.9118463223085;5883.646981181143;5911;5743.483086522233;5751.626144300607;5754.223179870748;5783.219265399117;5799.884002320015;5766.94233057664;5787.71061446926;5828.825135245949;5838.941610583925;5840.970795145997;5241.842744717665;5563.805266815642;5576.804811831566;5594.524465420485;5635.577331700057;5497.862553436196;5529.479393166911;5544.7227638617105;5550.47270509301;5573.606598350412;5674.779922316722;5801.941980580309;5802.6808525531505;5830.358660547421;5849.766009359578;3741.4948981887096;4057;4095.3584937714413;4102;4112.239235741397;5626.774929002958;5775.740091695968;5787.305523337226;5815.738291776795;5817.2257461474655;5507.284053073075;5537.612367134262;5548.722563871871;5550.250716153268;5584.855104703444;5564.248826408383;5574.777008919597;5598;5613.663180209201;5621.409751976147;5383.515483606494;5533.861653458695;5562.275694534545;5581.581381396492;5615.8876822464135;5594.831850271574;5606.047736345785;5614.943850561442;5618.803341883015;5632.690202038927;5439.211314359317;5440.374356948843;5440.782368705208;5491.752871120889;5508.237364043468 08:30:27 INFO - PROCESS | 31935 | |5;yui.html;718.5580867766325;744.7505085796258;763.0919206144688;812.2405550809993;835.0730688935282;14560.70878582429;15891.761623575656;15926.442574509894;15927.44253951112;15952.282147303385;14827.184504852228;14883.627909302273;14901.105933643961;14941.402343906231;15044.548663540085;764.571839769728;775.2054144501889;778.6885245901628;781.6326326626477;789.250212298316;796.1560745609644;798.3054742374113;798.700487317256;798.9201079891993;799.0930294116191;835.7492752174354;836.9079401265856;837.5100566168799;837.660747397305;837.949723016621;832.6877420967137;832.8584140696066;833.2459124492344;833.5748768128237;833.6790335720791;607.5200591532683;609.0954931926086;609.1411110334451;609.8871708733855;610.1793088296234;15982.520524384287;16029.679406411806;16178.27197776103;16228;16522.669546609002;13007.86992130076;14334.354954027094;14619.049761765451;14845.554633361016;14846.777298340534;11660.125490588154;11750.941245293805;11841.230320029257;12010.879891201064;12097.334646594434;3668.7248456365614;3738;3765.8870233893026;3776.2636285924254;3787.734858559902;39619.80190099031;47912.5208747915;62132;74448;75986;11206.159538034517;11313.547458101748;11339.262947908359;11537.826932596017;11834.289942603471;19822.20711171566;20709.275175368788;20718.792812071988;21697.13211471555;21832.126714931226;8370.748877533684;8441.535715535581;8482.109378515168;8494.405391622651;8559.74320770378;4536.478304994967;4537.38745269391;4617.884552886205;4619.976900115478;4778.617710583145;3439.1917899293644;3490.1798077451776;3512.1570823002808;3530.135116896377;3543.291341731664;595.214317101422;595.4670569840056;610.9419605137505;611.1932249430708;621.9844503887349;652.5138771615083;680.1905732178692;694.7255833945563;704.3555147040503;713.2368365848492;10052.698419047294;10256.641017564349;10357.844632330522;10473.319234249902;10725.624603138853;690.3441730356182;718.2960698515379;748.842743023971;750.2984709296825;754.705664790732;4643.419572553431;4969.130402179604;5062;5075.3402057733;5078;70746.64626676834;73646.63176684083;74639;74775.25224747791;76349.6182519095;43527;55381.72309138429;63249.36750632527;63309.366906330346;64023.359766402675;47343.5265647346;47586.28620570694;48264;51626.74186629043;58678.413215867295;10715.839262411226;11094.223404361617;11139.665810025712;11180.944095279472;11408.714782130513;37787.62212377951;38051.42922856104;38252.234955300184;38719.225615488096;39106.80446597749;45333.32000019936;45447.545524545654;46818.53181468142;48925;51508;13466.124701894345;13552.864471355555;13579.388927498483;13890.444382224598;14167.149971001772;14961.700765984837;15083.698326033636;15362.846371536589;15421.537353879401;15881.52355429339;13762.311884405539;14213.928930355281;14232.217228052557;14376.712465750836;14962.775558366415;14003;14524.927375363055;14533.781993269899;14541.709165816836;14653.413863445343;13713.588592342247;13845.169289842239;14156;14731.410743570137;14756.63108422298;10865.782684346426;11196.608118715805;11201.133483666248;11493.252938558968;11523.769524609628;9734.172595329477;9774.462404567532;10773.19201059908;10938.179636527451;11041.889581104086 08:30:27 INFO - PROCESS | 31935 | __end_tp_report 08:30:27 INFO - PROCESS | 31935 | __start_cc_report 08:30:27 INFO - PROCESS | 31935 | _x_x_mozilla_cycle_collect,1634 08:30:27 INFO - PROCESS | 31935 | __end_cc_report 08:30:27 INFO - PROCESS | 31935 | __startTimestamp1487349026991__endTimestamp 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | ------- Summary: start ------- 08:30:27 INFO - PROCESS | 31935 | Number of tests: 6 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#0] dojo.html Cycles:165 Average:5259.99 Median:5446.16 stddev:577.94 (10.6%) stddev-sans-first:554.90 08:30:27 INFO - PROCESS | 31935 | Values: 3124.8 3156.2 3173.2 3175.3 3183.4 5162.6 5205.6 5259.6 5288.6 5352.4 5194.4 5212.1 5213.4 5231.6 5251.9 4416.8 4612.3 4842.2 4864.0 4865.7 4681.4 4725.3 4806.1 4825.0 4850.2 4702.8 4734.7 4753.7 4763.4 4782.0 4739.8 4759.1 4764.8 4839.6 4843.2 4736.4 4741.6 4751.6 4758.9 4773.1 5159.1 5193.2 5293.2 5297.6 5330.4 5658.4 5663.3 5670.8 5676.7 5679.9 5578.1 5636.6 5655.2 5667.2 5705.8 5268.2 5315.5 5374.4 5376.1 5376.3 5763.3 5770.7 5772.6 5772.9 5794.7 5452.3 5488.6 5496.0 5563.3 5564.6 5632.6 5636.3 5640.5 5640.9 5644.1 5611.9 5614.4 5637.2 5653.5 5775.3 5746.0 5787.8 5787.9 5795.6 5801.1 4451.7 4456.8 4459.3 4461.2 4500.3 3906.2 3978.0 3982.1 3993.7 4063.2 5290.3 5302.1 5348.3 5350.6 5354.7 5683.1 5749.5 5773.5 5774.5 5777.2 5386.3 5390.1 5392.6 5398.1 5436.6 4913.1 4915.7 4919.2 4925.3 4961.6 5625.8 5676.2 5680.6 5733.8 5922.6 5540.3 5548.3 5625.5 5644.9 5721.6 5826.7 5876.6 5902.1 5914.7 5932.4 5573.7 5576.5 5580.8 5586.1 5587.7 5590.8 5621.3 5682.6 5686.1 5691.2 5445.5 5501.4 5517.2 5530.4 5530.8 5535.7 5536.0 5546.3 5561.3 5588.4 5480.9 5548.6 5552.1 5569.6 5603.0 5409.7 5444.3 5453.8 5606.2 5696.7 5337.3 5412.1 5424.6 5437.4 5446.2 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#1] ext.html Cycles:180 Average:48291.08 Median:36305.91 stddev:85159.09 (234.6%) stddev-sans-first:85322.50 08:30:27 INFO - PROCESS | 31935 | Values: 537.8 562.0 575.3 604.4 626.4 29578.0 30218.8 41164.8 43558.6 43760.8 44693.3 44800.8 45070.3 45140.0 45162.3 9093.9 9274.0 9290.9 9308.0 9309.2 7090.9 7259.4 9126.7 10218.4 10450.3 10988.3 11067.3 11068.2 11070.7 11080.6 10943.1 11017.4 11023.3 11044.2 11055.6 10324.7 10409.5 10419.9 10421.1 10424.3 41367.8 41592.4 41693.2 41704.2 41754.0 3796.0 3869.0 3877.6 3909.6 3952.8 31446.2 31538.4 31544.7 31651.5 32138.8 4101.6 4208.5 4210.3 4215.0 4332.8 215853.0 215923.0 216289.0 216900.8 217335.0 54888.7 54995.0 55313.2 55330.2 55340.0 50138.2 51186.5 56630.4 58265.0 58273.0 14975.7 15080.1 15146.0 15164.5 15183.1 11210.8 11318.3 11325.3 11428.0 11455.1 15540.8 15703.7 15757.8 15771.0 15797.3 6279.5 6306.2 6374.7 6434.2 6486.5 876.4 878.8 883.8 883.9 905.7 30061.6 30238.8 30915.5 30977.4 31204.7 1014.5 1033.5 1037.8 1070.8 1199.3 15480.6 16403.8 16425.6 16466.5 16629.4 405137.0 408781.0 409570.0 409625.0 410039.0 59892.7 60147.7 60248.4 60270.4 60280.0 1293.1 1333.3 1336.8 1354.3 1357.8 27945.3 29182.6 29599.0 30149.0 30188.0 36293.0 36430.8 36523.3 36524.3 36556.5 314880.0 316102.0 316831.0 317539.0 318347.0 37501.2 37854.7 38100.2 38151.6 38239.2 43720.6 43955.6 44060.6 44882.1 44979.6 34341.5 34509.8 35743.6 36968.6 37340.8 35633.3 36318.8 36419.8 36466.8 36518.0 35306.6 35618.5 36394.6 36702.4 36973.8 29167.0 31485.7 31607.5 31689.2 31853.8 24283.6 24749.6 24798.5 25263.1 25310.4 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#2] jquery.html Cycles:180 Average:32497.25 Median:20319.91 stddev:100013.45 (492.2%) stddev-sans-first:100264.91 08:30:27 INFO - PROCESS | 31935 | Values: 364.4 378.8 379.6 393.8 397.6 13658.2 14427.0 15993.3 19633.2 20896.5 13615.0 16905.4 19137.8 19548.7 19668.5 5473.5 5659.4 5660.3 5664.3 5665.8 4652.5 5505.3 6177.0 6207.2 6316.4 6232.3 6315.8 6322.3 6328.3 6330.5 6761.6 6818.9 6823.9 6850.2 6854.8 5521.8 5738.7 5787.4 6141.0 6169.8 22581.4 23296.7 23479.8 23674.6 24024.8 2619.1 2649.3 2691.1 2704.7 2709.3 9626.5 9763.4 9816.5 10254.1 10312.4 1769.5 1842.7 1853.5 1864.9 1867.8 78853.6 81223.0 81393.0 88318.1 88605.6 11872.8 11998.9 12018.9 12025.8 12437.4 17355.6 17402.3 17418.0 17422.9 17427.0 7779.3 7988.7 8106.6 8154.1 8382.7 7358.9 7362.4 7610.0 7612.0 7664.4 5091.2 5142.3 5223.2 5275.9 5337.5 1929.6 1977.4 1984.1 1992.8 2028.8 841.7 864.6 874.3 881.0 887.1 19329.3 20495.2 20650.4 20662.5 20924.8 871.6 874.9 878.7 887.4 896.8 10863.8 11450.7 11483.6 11508.5 11515.0 595548.0 597209.0 599401.1 623202.0 629386.0 78034.6 85740.0 101895.0 102117.5 103307.0 995.6 1032.3 1049.1 1059.5 1059.9 17029.2 17405.1 17847.2 17885.3 18064.4 33358.5 40242.2 44102.8 45135.8 46028.0 54534.7 59647.7 61236.4 62253.4 62341.4 8157.3 8391.0 8462.6 8685.4 8963.3 9175.7 9233.5 9953.4 12907.2 12943.2 11304.9 11506.9 11533.4 11641.7 12007.2 11162.7 11189.7 11237.3 11515.9 11774.4 11695.6 11871.7 11986.6 12173.5 12208.3 7739.1 7745.4 7947.5 8490.0 8506.4 8759.6 10919.6 11700.7 11737.9 11932.9 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#3] mootools.html Cycles:180 Average:5080.36 Median:4868.57 stddev:2859.86 (58.7%) stddev-sans-first:2853.11 08:30:27 INFO - PROCESS | 31935 | Values: 1212.4 1394.7 1410.3 1428.5 1436.9 6866.3 7607.6 7957.6 7975.4 8139.2 7939.8 7994.9 7996.2 8005.3 8006.7 1817.9 2016.5 2047.3 2052.2 2052.4 1846.6 1864.3 2102.4 2173.8 2177.9 2157.4 2157.9 2159.2 2166.2 2167.2 1961.7 1985.6 1986.3 1990.8 1996.9 2077.2 2114.6 2142.5 2148.6 2152.0 8043.5 8055.2 8062.2 8101.0 8113.4 4732.7 4839.4 4845.5 4852.5 4864.6 3007.8 3148.1 3192.1 3193.6 3199.0 1214.8 1217.4 1234.1 1238.7 1246.0 10704.3 10750.7 10757.4 10761.8 10770.6 3986.9 4205.7 4254.5 4257.6 4294.9 9255.3 9260.9 9261.8 9266.2 9271.4 4969.6 4983.4 4985.6 4985.8 4986.2 4333.1 4508.1 4529.5 4598.1 4598.2 3263.7 3297.7 3302.5 3506.6 3609.7 1134.7 1141.6 1169.3 1172.1 1179.9 813.7 833.3 837.0 850.2 857.7 4809.1 4857.2 4872.6 4885.1 5036.7 866.6 883.7 884.9 885.6 890.9 3111.7 3136.9 3141.0 3178.0 3308.8 9182.5 9481.1 9488.6 9497.8 9523.9 9472.6 9480.3 9481.1 9485.1 9488.4 7247.6 7309.2 7408.0 7421.0 7512.8 4698.1 4741.2 4755.4 4776.8 4879.2 8635.0 8644.7 8651.0 8657.6 8745.9 8339.3 8638.7 8670.7 8747.2 8888.2 6827.0 6935.3 6954.7 6956.0 6958.4 6533.3 6762.1 6992.5 7158.0 7236.5 6561.3 6584.7 6701.1 6739.1 6788.8 6549.2 6567.2 6592.1 6612.4 6632.9 5502.5 5504.5 5543.8 5584.7 5604.2 4486.3 4553.4 4606.2 4635.0 4699.6 4371.2 4414.1 4530.5 4611.7 4681.1 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#4] prototype.html Cycles:180 Average:4704.68 Median:5281.12 stddev:1341.57 (25.4%) stddev-sans-first:1305.47 08:30:27 INFO - PROCESS | 31935 | Values: 380.0 388.1 389.7 393.5 397.4 5178.6 5264.3 5291.5 5302.2 5317.3 5133.2 5146.6 5222.4 5264.7 5298.2 4158.0 4169.6 4170.4 4177.6 4177.6 4151.9 4198.6 4210.3 4210.7 4217.2 4000.3 4133.8 4181.9 4188.3 4195.7 4104.4 4107.9 4124.5 4134.0 4149.2 3715.5 3717.6 3723.1 3726.4 3728.9 5177.3 5182.6 5182.8 5188.0 5211.6 4737.9 4795.8 4816.7 4861.8 4903.9 5553.2 5580.4 5595.6 5604.3 5642.8 4175.9 4196.0 4205.6 4217.6 4232.4 5555.0 5706.1 5719.3 5755.5 5805.8 4119.4 4122.7 4137.9 4169.7 4304.8 4339.5 4344.0 4346.9 4350.0 4379.3 4685.6 4883.3 4942.0 4943.0 4956.8 5550.8 5655.8 5683.1 5685.1 5685.8 1482.5 1507.4 1510.5 1511.3 1513.3 996.8 1050.4 1057.1 1070.7 1087.9 5240.3 5273.2 5273.6 5274.4 5287.8 5562.6 5577.7 5590.2 5637.7 5679.7 5108.3 5304.1 5333.5 5411.4 5441.8 2998.7 3001.5 3009.6 3015.1 3024.3 5571.8 5874.5 5876.9 5883.6 5911.0 5743.5 5751.6 5754.2 5783.2 5799.9 5766.9 5787.7 5828.8 5838.9 5841.0 5241.8 5563.8 5576.8 5594.5 5635.6 5497.9 5529.5 5544.7 5550.5 5573.6 5674.8 5801.9 5802.7 5830.4 5849.8 3741.5 4057.0 4095.4 4102.0 4112.2 5626.8 5775.7 5787.3 5815.7 5817.2 5507.3 5537.6 5548.7 5550.3 5584.9 5564.2 5574.8 5598.0 5613.7 5621.4 5383.5 5533.9 5562.3 5581.6 5615.9 5594.8 5606.0 5614.9 5618.8 5632.7 5439.2 5440.4 5440.8 5491.8 5508.2 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - PROCESS | 31935 | [#5] yui.html Cycles:180 Average:16145.29 Median:38485.73 stddev:19027.54 (49.4%) stddev-sans-first:19045.65 08:30:27 INFO - PROCESS | 31935 | Values: 718.6 744.8 763.1 812.2 835.1 14560.7 15891.8 15926.4 15927.4 15952.3 14827.2 14883.6 14901.1 14941.4 15044.5 764.6 775.2 778.7 781.6 789.3 796.2 798.3 798.7 798.9 799.1 835.7 836.9 837.5 837.7 837.9 832.7 832.9 833.2 833.6 833.7 607.5 609.1 609.1 609.9 610.2 15982.5 16029.7 16178.3 16228.0 16522.7 13007.9 14334.4 14619.0 14845.6 14846.8 11660.1 11750.9 11841.2 12010.9 12097.3 3668.7 3738.0 3765.9 3776.3 3787.7 39619.8 47912.5 62132.0 74448.0 75986.0 11206.2 11313.5 11339.3 11537.8 11834.3 19822.2 20709.3 20718.8 21697.1 21832.1 8370.7 8441.5 8482.1 8494.4 8559.7 4536.5 4537.4 4617.9 4620.0 4778.6 3439.2 3490.2 3512.2 3530.1 3543.3 595.2 595.5 610.9 611.2 622.0 652.5 680.2 694.7 704.4 713.2 10052.7 10256.6 10357.8 10473.3 10725.6 690.3 718.3 748.8 750.3 754.7 4643.4 4969.1 5062.0 5075.3 5078.0 70746.6 73646.6 74639.0 74775.3 76349.6 43527.0 55381.7 63249.4 63309.4 64023.4 47343.5 47586.3 48264.0 51626.7 58678.4 10715.8 11094.2 11139.7 11180.9 11408.7 37787.6 38051.4 38252.2 38719.2 39106.8 45333.3 45447.5 46818.5 48925.0 51508.0 13466.1 13552.9 13579.4 13890.4 14167.1 14961.7 15083.7 15362.8 15421.5 15881.5 13762.3 14213.9 14232.2 14376.7 14962.8 14003.0 14524.9 14533.8 14541.7 14653.4 13713.6 13845.2 14156.0 14731.4 14756.6 10865.8 11196.6 11201.1 11493.3 11523.8 9734.2 9774.5 10773.2 10938.2 11041.9 08:30:27 INFO - PROCESS | 31935 | -------- Summary: end -------- 08:30:27 INFO - PROCESS | 31935 | 08:30:27 INFO - TEST-INFO | 31935: exit 0 08:30:27 INFO - TEST-OK | dromaeo_css | took 1117188ms 08:30:27 INFO - TEST-START | kraken 08:30:27 INFO - operating with platform_type : linux_ 08:30:27 INFO - Initialising browser for kraken test... 08:30:27 INFO - TEST-INFO | started process 32257 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwToKco/profile http://localhost:45907/getInfo.html) 08:30:30 INFO - PROCESS | 32257 | Iterating over profile /tmp/tmpwToKco/profile 08:30:31 INFO - PROCESS | 32257 | __metrics Screen width/height:1600/1200 08:30:31 INFO - PROCESS | 32257 | colorDepth:24 08:30:31 INFO - PROCESS | 32257 | Browser inner width/height: 1024/697 08:30:31 INFO - PROCESS | 32257 | __metrics 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/blocklist.xml = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/places.sqlite-shm = Fri Feb 17 2017 08:30:29 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/sessionCheckpoints.json = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/content-prefs.sqlite = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/safebrowsing = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/places.sqlite-wal = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/extensions.ini = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/extensions = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/pluginreg.dat = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/permissions.sqlite = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/gmp = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/secmod.db = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/prefs.js = Fri Feb 17 2017 08:11:08 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/addons.json = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/compatibility.ini = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/cookies.sqlite = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/storage.sqlite-journal = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/directoryLinks.json = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | Stat failure 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/mimeTypes.rdf = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | Stat failure 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/user.js = Fri Feb 17 2017 08:30:27 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/startupCache = Fri Feb 17 2017 08:30:27 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/minidumps = Fri Feb 17 2017 08:30:27 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/webappsstore.sqlite = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/storage.sqlite = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/thumbnails = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/key3.db = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/cert8.db = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/places.sqlite = Fri Feb 17 2017 08:30:31 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/extensions.json = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/crashes = Fri Feb 17 2017 08:30:27 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/localstore.rdf = Fri Feb 17 2017 08:11:08 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/bookmarkbackups = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/cache2 = Fri Feb 17 2017 08:30:28 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/.parentlock = Fri Feb 17 2017 08:30:27 GMT-0800 (PST) 08:30:31 INFO - PROCESS | 32257 | No birth date. Using mtime. 08:30:31 INFO - PROCESS | 32257 | Using date: /tmp/tmpwToKco/profile/frequencyCap.json = Fri Feb 17 2017 08:30:30 GMT-0800 (PST) 08:30:32 INFO - PROCESS | 32257 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:30:32 INFO - PROCESS | 32257 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:33 INFO - TEST-INFO | 32257: exit 0 08:30:33 INFO - Browser initialized. 08:30:33 INFO - Running cycle 1/1 for kraken test... 08:30:33 INFO - TEST-INFO | started process 32353 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwToKco/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:45 INFO - PROCESS | 32353 | RSS: Main: 142839808 08:30:45 INFO - PROCESS | 32353 | 08:30:48 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45907/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:48 INFO - PROCESS | 32353 | RSS: Main: 198451200 08:30:48 INFO - PROCESS | 32353 | 08:30:51 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45907/tests/kraken/driver.html?testName=audio-dft) 08:30:52 INFO - PROCESS | 32353 | RSS: Main: 177442816 08:30:52 INFO - PROCESS | 32353 | 08:30:55 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45907/tests/kraken/driver.html?testName=audio-fft) 08:30:55 INFO - PROCESS | 32353 | RSS: Main: 258822144 08:30:55 INFO - PROCESS | 32353 | 08:30:57 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45907/tests/kraken/driver.html?testName=audio-oscillator) 08:30:58 INFO - PROCESS | 32353 | RSS: Main: 170672128 08:30:58 INFO - PROCESS | 32353 | 08:31:00 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45907/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:01 INFO - PROCESS | 32353 | RSS: Main: 174510080 08:31:01 INFO - PROCESS | 32353 | 08:31:04 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45907/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:04 INFO - PROCESS | 32353 | RSS: Main: 213770240 08:31:04 INFO - PROCESS | 32353 | 08:31:08 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45907/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:08 INFO - PROCESS | 32353 | RSS: Main: 213504000 08:31:08 INFO - PROCESS | 32353 | 08:31:11 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45907/tests/kraken/driver.html?testName=json-parse-financial) 08:31:12 INFO - PROCESS | 32353 | RSS: Main: 212193280 08:31:12 INFO - PROCESS | 32353 | 08:31:13 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45907/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:14 INFO - PROCESS | 32353 | RSS: Main: 174755840 08:31:14 INFO - PROCESS | 32353 | 08:31:15 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:16 INFO - PROCESS | 32353 | RSS: Main: 181096448 08:31:16 INFO - PROCESS | 32353 | 08:31:18 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:19 INFO - PROCESS | 32353 | RSS: Main: 190107648 08:31:19 INFO - PROCESS | 32353 | 08:31:21 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:31:22 INFO - PROCESS | 32353 | RSS: Main: 199581696 08:31:22 INFO - PROCESS | 32353 | 08:31:25 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:26 INFO - PROCESS | 32353 | RSS: Main: 196091904 08:31:26 INFO - PROCESS | 32353 | 08:31:27 INFO - PROCESS | 32353 | Cycle 1(1): loaded http://localhost:45907/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45907/tests/kraken/driver.html?testName=ai-astar) 08:31:28 INFO - PROCESS | 32353 | RSS: Main: 248930304 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | __start_tp_report 08:31:28 INFO - PROCESS | 32353 | _x_x_mozilla_page_load 08:31:28 INFO - PROCESS | 32353 | _x_x_mozilla_page_load_details 08:31:28 INFO - PROCESS | 32353 | |i|pagename|runs| 08:31:28 INFO - PROCESS | 32353 | |0;ai-astar;113;114;113;112;122;113;111;112;110;110 08:31:28 INFO - PROCESS | 32353 | |1;audio-beat-detection;142;121;126;161;121;123;121;121;155;123 08:31:28 INFO - PROCESS | 32353 | |2;audio-dft;163;162;161;162;161;163;163;163;163;163 08:31:28 INFO - PROCESS | 32353 | |3;audio-fft;75;78;77;111;77;75;77;76;102;77 08:31:28 INFO - PROCESS | 32353 | |4;audio-oscillator;84;83;114;84;83;83;100;85;84;109 08:31:28 INFO - PROCESS | 32353 | |5;imaging-gaussian-blur;113;116;113;114;113;113;113;113;113;114 08:31:28 INFO - PROCESS | 32353 | |6;imaging-darkroom;148;148;148;147;147;147;148;148;148;147 08:31:28 INFO - PROCESS | 32353 | |7;imaging-desaturate;97;96;96;96;96;96;96;96;96;96 08:31:28 INFO - PROCESS | 32353 | |8;json-parse-financial;56;53;54;53;54;55;55;54;54;56 08:31:28 INFO - PROCESS | 32353 | |9;json-stringify-tinderbox;64;66;64;64;65;64;64;64;65;65 08:31:28 INFO - PROCESS | 32353 | |10;stanford-crypto-aes;85;85;85;84;84;84;83;84;92;87 08:31:28 INFO - PROCESS | 32353 | |11;stanford-crypto-ccm;132;139;139;133;131;131;132;138;171;131 08:31:28 INFO - PROCESS | 32353 | |12;stanford-crypto-pbkdf2;175;175;173;179;171;173;175;175;172;173 08:31:28 INFO - PROCESS | 32353 | |13;stanford-crypto-sha256-iterative;73;67;64;67;63;64;65;64;63;62 08:31:28 INFO - PROCESS | 32353 | __end_tp_report 08:31:28 INFO - PROCESS | 32353 | __start_cc_report 08:31:28 INFO - PROCESS | 32353 | _x_x_mozilla_cycle_collect,1766 08:31:28 INFO - PROCESS | 32353 | __end_cc_report 08:31:28 INFO - PROCESS | 32353 | __startTimestamp1487349088188__endTimestamp 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | ------- Summary: start ------- 08:31:28 INFO - PROCESS | 32353 | Number of tests: 14 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:3.43 (3.0%) stddev-sans-first:3.64 08:31:28 INFO - PROCESS | 32353 | Values: 113.0 114.0 113.0 112.0 122.0 113.0 111.0 112.0 110.0 110.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:124.50 stddev:15.45 (12.4%) stddev-sans-first:15.90 08:31:28 INFO - PROCESS | 32353 | Values: 142.0 121.0 126.0 161.0 121.0 123.0 121.0 121.0 155.0 123.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#2] audio-dft Cycles:10 Average:162.40 Median:163.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 08:31:28 INFO - PROCESS | 32353 | Values: 163.0 162.0 161.0 162.0 161.0 163.0 163.0 163.0 163.0 163.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#3] audio-fft Cycles:10 Average:82.50 Median:77.00 stddev:12.86 (16.7%) stddev-sans-first:13.35 08:31:28 INFO - PROCESS | 32353 | Values: 75.0 78.0 77.0 111.0 77.0 75.0 77.0 76.0 102.0 77.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:83.50 stddev:12.06 (14.4%) stddev-sans-first:12.53 08:31:28 INFO - PROCESS | 32353 | Values: 84.0 83.0 114.0 84.0 83.0 83.0 100.0 85.0 84.0 109.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 08:31:28 INFO - PROCESS | 32353 | Values: 113.0 116.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 114.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.52 (0.3%) stddev-sans-first:0.53 08:31:28 INFO - PROCESS | 32353 | Values: 148.0 148.0 148.0 147.0 147.0 147.0 148.0 148.0 148.0 147.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 08:31:28 INFO - PROCESS | 32353 | Values: 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.07 (2.0%) stddev-sans-first:0.97 08:31:28 INFO - PROCESS | 32353 | Values: 56.0 53.0 54.0 53.0 54.0 55.0 55.0 54.0 54.0 56.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 08:31:28 INFO - PROCESS | 32353 | Values: 64.0 66.0 64.0 64.0 65.0 64.0 64.0 64.0 65.0 65.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.58 (3.0%) stddev-sans-first:2.74 08:31:28 INFO - PROCESS | 32353 | Values: 85.0 85.0 85.0 84.0 84.0 84.0 83.0 84.0 92.0 87.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:135.50 stddev:12.18 (9.0%) stddev-sans-first:12.74 08:31:28 INFO - PROCESS | 32353 | Values: 132.0 139.0 139.0 133.0 131.0 131.0 132.0 138.0 171.0 131.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:175.00 stddev:2.23 (1.3%) stddev-sans-first:2.35 08:31:28 INFO - PROCESS | 32353 | Values: 175.0 175.0 173.0 179.0 171.0 173.0 175.0 175.0 172.0 173.0 08:31:28 INFO - PROCESS | 32353 | 08:31:28 INFO - PROCESS | 32353 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:64.50 stddev:3.19 (4.9%) stddev-sans-first:1.73 08:31:28 INFO - PROCESS | 32353 | Values: 73.0 67.0 64.0 67.0 63.0 64.0 65.0 64.0 63.0 62.0 08:31:28 INFO - PROCESS | 32353 | -------- Summary: end -------- 08:31:28 INFO - PROCESS | 32353 | 08:31:29 INFO - TEST-INFO | 32353: exit 0 08:31:29 INFO - TEST-OK | kraken | took 61484ms 08:31:29 INFO - SUITE-END | took 1178s 08:31:29 INFO - Completed test suite (00:19:38) 08:31:29 INFO - javascript benchmark 08:31:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6973.625651971375, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.7500199984, 3156.179393357729, 3173.2225604726846, 3175.333180032193, 3183.4269831430347, 5162.6128040397025, 5205.60957928155, 5259.579233661308, 5288.603354748395, 5352.357717073952, 5194.37667479902, 5212.113940630093, 5213.400458947222, 5231.555317797982, 5251.947480525194, 4416.80124394403, 4612.262038073917, 4842.249451335031, 4864.0, 4865.70805751656, 4681.414823147106, 4725.3148293497425, 4806.0628177505405, 4824.951750482503, 4850.223964165734, 4702.811887524529, 4734.739589322586, 4753.667243292971, 4763.404574428214, 4781.95218047822, 4739.834105806315, 4759.095771803346, 4764.8332308369045, 4839.612830973514, 4843.15244832153, 4736.384270044872, 4741.57325840676, 4751.619870410359, 4758.881027974294, 4773.11697335994, 5159.071367153948, 5193.195054766517, 5293.153095504739, 5297.602679799031, 5330.38700549434, 5658.405867383906, 5663.348714897798, 5670.8015219467125, 5676.716164191774, 5679.886402271932, 5578.107502799533, 5636.633618814805, 5655.179998900177, 5667.234923285386, 5705.800296989627, 5268.1834315681135, 5315.495027972375, 5374.435684253136, 5376.1129413647095, 5376.274202982587, 5763.308402991668, 5770.740316685759, 5772.5670574707065, 5772.942270577324, 5794.739236734357, 5452.345718513724, 5488.615796894259, 5496.0107180707055, 5563.332400111932, 5564.610477266633, 5632.633878797865, 5636.323641163087, 5640.5487560995025, 5640.915386269209, 5644.068728659808, 5611.915821262684, 5614.438556144353, 5637.238972738628, 5653.491185793298, 5775.306963164448, 5746.02317606008, 5787.797427090116, 5787.913181302284, 5795.594308398456, 5801.12983052537, 4451.710638808532, 4456.799444025027, 4459.264221403497, 4461.174682683714, 4500.324951257272, 3906.1797022625, 3977.960220397817, 3982.143839074613, 3993.740406873602, 4063.1670507546473, 5290.259363689087, 5302.072137376021, 5348.304720386325, 5350.571954243651, 5354.705491197944, 5683.090705487102, 5749.540036797046, 5773.480386765212, 5774.509166720792, 5777.162311464898, 5386.326709161355, 5390.0836857734275, 5392.56859451243, 5398.13629819227, 5436.565074794007, 4913.140200464904, 4915.65590408668, 4919.188333924933, 4925.270725813718, 4961.603071754251, 5625.831225063254, 5676.233708449389, 5680.573956953162, 5733.827985160452, 5922.555808314393, 5540.279763630702, 5548.250986116984, 5625.46558076982, 5644.887102258014, 5721.570882183936, 5826.70866456684, 5876.6180198286975, 5902.055671092605, 5914.704264786657, 5932.406759324033, 5573.749181286771, 5576.498115169654, 5580.776768929198, 5586.050371436947, 5587.664740115606, 5590.7763689453595, 5621.269234999424, 5682.602217844711, 5686.061799803151, 5691.174779657009, 5445.455454454523, 5501.449855014466, 5517.2275881377445, 5530.364008139116, 5530.7511161997, 5535.6955367455175, 5536.031194541019, 5546.251256080377, 5561.304836895388, 5588.413216612197, 5480.945190548044, 5548.639338443149, 5552.139418390156, 5569.55443564522, 5603.0, 5409.675419474844, 5444.26502422168, 5453.809116680897, 5606.159076138611, 5696.715164241854, 5337.279467272024, 5412.134058550613, 5424.5660347172925, 5437.374701909331, 5446.1558458439], "value": 5223.017288135967, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.760696490062, 561.9859503512412, 575.3441077172025, 604.3503234023423, 626.3548544998653, 29578.0, 30218.84890575547, 41164.79417602915, 43558.564414355846, 43760.78119609405, 44693.32960005594, 44800.77599611998, 45070.32394514085, 45140.0, 45162.32256516155, 9093.863592046124, 9273.95363023184, 9290.860637090414, 9308.0, 9309.162175404212, 7090.85818283634, 7259.419246460297, 9126.680566180185, 10218.437985910772, 10450.320729152581, 10988.340699558012, 11067.335959842396, 11068.225224234307, 11070.667879963614, 11080.612178573752, 10943.124550036018, 11017.44912754367, 11023.338599684044, 11044.226904116715, 11055.613053543168, 10324.690259292232, 10409.479526023668, 10419.947900260526, 10421.062104410563, 10424.270301078928, 41367.7931610343, 41592.37611435831, 41693.16613667709, 41704.16591668149, 41754.0, 3796.0116968969437, 3869.0, 3877.631624995649, 3909.6285852843935, 3952.802359882023, 31446.213844654067, 31538.36923261545, 31544.684553154406, 31651.525227121612, 32138.83930580332, 4101.630853223194, 4208.473940757405, 4210.32634778437, 4215.0, 4332.761698106603, 215853.0, 215923.0, 216288.99999999843, 216900.83099168964, 217335.0, 54888.72555637276, 54995.0, 55313.1703024455, 55330.17004744932, 55340.0, 50138.22028263868, 51186.488135118176, 56630.43369566252, 58265.0, 58273.0, 14975.700485990219, 15080.095194288378, 15146.0, 15164.469243576423, 15183.089014659156, 11210.831837522444, 11318.264312819805, 11325.263857849379, 11428.0, 11455.083593312514, 15540.766888496682, 15703.68592628164, 15757.763633545506, 15771.0, 15797.289121989537, 6279.466245369194, 6306.180196574416, 6374.681265936777, 6434.163558737334, 6486.481081513468, 876.3602570123845, 878.8399312907029, 883.8497455432592, 883.9071897450804, 905.6558507767006, 30061.63489944934, 30238.84880575627, 30915.536266956013, 30977.44360902238, 31204.687953120178, 1014.4826138669332, 1033.5142483033112, 1037.7561273100816, 1070.8072546941626, 1199.3463562358536, 15480.604393413758, 16403.83596164047, 16425.58936026585, 16466.506004819876, 16629.417970370978, 405137.0, 408781.0, 409570.0, 409625.0, 410039.0, 59892.70053649704, 60147.69926150341, 60248.39751602516, 60270.39729602648, 60280.0, 1293.1142167615214, 1333.3333333333333, 1336.7593833109938, 1354.3499120422055, 1357.8234829472106, 27945.301367465978, 29182.56226156652, 29599.0, 30149.0, 30188.0, 36293.0, 36430.8178459106, 36523.26953460969, 36524.26951461009, 36556.45165322469, 314880.0, 316102.0, 316831.0, 317539.0, 318347.0, 37501.249975000894, 37854.72871627711, 38100.237995240495, 38151.618483814804, 38239.23521529498, 43720.562794371646, 43955.560444395145, 44060.559394405645, 44882.106241845286, 44979.550204498846, 34341.48487772735, 34509.827450862584, 35743.64256357507, 36968.63031369652, 37340.813295933345, 35633.28733425265, 36318.818405907805, 36419.817900910326, 36466.8176659115, 36518.0, 35306.64693353137, 35618.46572301366, 36394.636053640184, 36702.44946325754, 36973.81513092417, 29167.0, 31485.685143148276, 31607.525887112173, 31689.20776980594, 31853.840730796197, 24283.635745464184, 24749.62875556832, 24798.504029919663, 25263.115790947228, 25310.367240819127], "value": 18843.24639264074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.41875209041604, 378.7765218521072, 379.62417206965097, 393.82671624485243, 397.63020391036343, 13658.248796316211, 14427.0, 15993.280302386376, 19633.214671413163, 20896.477588060305, 13615.046946713734, 16905.40831070913, 19137.808621913813, 19548.70676939847, 19668.508287292825, 5473.480019398151, 5659.377468478464, 5660.29246344207, 5664.348599911001, 5665.830025099253, 4652.511486293941, 5505.256790333294, 6177.042558403455, 6207.193064901557, 6316.399942005525, 6232.252129744437, 6315.842103947392, 6322.272938612049, 6328.303886572473, 6330.493560515148, 6761.594304341756, 6818.863622727567, 6823.863522729543, 6850.177978642545, 6854.794356169323, 5521.751521181516, 5738.6556806591325, 5787.421257874179, 6141.048137538689, 6169.7840575580085, 22581.435464113365, 23296.65055024176, 23479.7652023481, 23674.64488032681, 24024.759752402428, 2619.122593931035, 2649.337665583604, 2691.1284492858313, 2704.688960769517, 2709.295583148376, 9626.51867406634, 9763.365381250196, 9816.509174541316, 10254.128399086087, 10312.432816195107, 1769.4780039888265, 1842.6591080650123, 1853.508820162658, 1864.8787828791085, 1867.766529183852, 78853.60573197098, 81222.9999999994, 81393.0, 88318.11681883164, 88605.55697221472, 11872.76254474923, 11998.940005299917, 12018.939905300593, 12025.768790576127, 12437.440315185839, 17355.56611084733, 17402.303907843798, 17418.0, 17422.912885435493, 17427.0, 7779.299863012356, 7988.720394786154, 8106.554139522265, 8154.06228283755, 8382.706605268908, 7358.852822943618, 7362.411007119417, 7610.048743907012, 7612.0, 7664.386849052063, 5091.236314552847, 5142.331496905378, 5223.164293713064, 5275.894482110413, 5337.546308563816, 1929.5851391950798, 1977.367242482392, 1984.1468168687313, 1992.8306093982173, 2028.766691830429, 841.7306461932245, 864.6498168241872, 874.2743522875998, 880.9871829291312, 887.1394747095308, 19329.323473678636, 20495.180192792122, 20650.380488585368, 20662.48343791387, 20924.790752092285, 871.5598622695499, 874.8993865705526, 878.7451639024739, 887.4187407248263, 896.762357975124, 10863.83704244437, 11450.713732156597, 11483.598074067368, 11508.53965841357, 11515.0, 595548.0, 597209.0, 599401.068877076, 623202.0, 629386.0, 78034.60982695164, 85740.0, 101895.0, 102117.48941255246, 103307.0, 995.6166875752888, 1032.2670903658336, 1049.1292227451197, 1059.5496913811598, 1059.9099076578577, 17029.233684484476, 17405.115285612654, 17847.196876140344, 17885.28458861631, 18064.36774712879, 33358.49962250617, 40242.19515609613, 44102.779486102365, 45135.774321128185, 46028.0, 54534.727326363114, 59647.70176149091, 61236.387636123065, 62253.37746622476, 62341.37658623356, 8157.265846073882, 8390.958045209734, 8462.619182136696, 8685.435446695945, 8963.327750418614, 9175.678851240174, 9233.522636378148, 9953.353032052893, 12907.161034532724, 12943.223406595635, 11304.943475282571, 11506.94246528762, 11533.423328833693, 11641.708957276136, 12007.219530730474, 11162.665120046411, 11189.72025699364, 11237.269577477438, 11515.884841151481, 11774.411279435823, 11695.590654327058, 11871.703207419883, 11986.64040078764, 12173.513059477522, 12208.267503949792, 7739.110002349802, 7745.419093568117, 7947.483413578099, 8489.95755021221, 8506.362022848434, 8759.605817738344, 10919.56321747153, 11700.70748231301, 11737.88262117368, 11932.940335298268], "value": 9300.767334901866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1212.448336007117, 1394.7071115065835, 1410.2737090398443, 1428.4571862692176, 1436.8994170408075, 6866.279040700723, 7607.619619019048, 7957.562334071631, 7975.401844861644, 8139.186081391857, 7939.841203175933, 7994.92005079949, 7996.200379962001, 8005.279524842763, 8006.67973281068, 1817.9454616361531, 2016.5261163626535, 2047.2936836791282, 2052.2098991888156, 2052.363767232155, 1846.639905218483, 1864.2542982806917, 2102.432343267317, 2173.804357607815, 2177.923772667957, 2157.3635777445693, 2157.9244726434576, 2159.2442645074257, 2166.2309879992613, 2167.2089687264192, 1961.7155512450686, 1985.6326579582824, 1986.3147214211074, 1990.7710613279514, 1996.8901710405921, 2077.2106599492095, 2114.619368513667, 2142.4536743130557, 2148.5702859428175, 2151.9784802151935, 8043.477173983731, 8055.194480551956, 8062.193780621949, 8101.0, 8113.350931925491, 4732.716037037789, 4839.395075615548, 4845.515448455127, 4852.4662287148385, 4864.562189402971, 3007.8195308281356, 3148.071318960889, 3192.1381227068796, 3193.6486986431473, 3198.984005079983, 1214.7509760499165, 1217.3730528777687, 1234.0929416878678, 1238.6717519857154, 1245.9626211213588, 10704.30422022566, 10750.677479675622, 10757.354558726502, 10761.78476430467, 10770.569177232901, 3986.940195897064, 4205.726627769185, 4254.468191476066, 4257.6168144867115, 4294.935575966363, 9255.305852061123, 9260.907390926139, 9261.814763704688, 9266.166045055978, 9271.443713377219, 4969.552740253394, 4983.401991761012, 4985.576226020757, 4985.800567977313, 4986.202207646759, 4333.111712099012, 4508.098380323882, 4529.5243999380245, 4598.149342371672, 4598.218302888517, 3263.7225835803747, 3297.7196938260035, 3302.455094909313, 3506.63180366063, 3609.729270304685, 1134.733337665648, 1141.5662048421548, 1169.2575214738702, 1172.0682057764097, 1179.9351035693119, 813.7198336748744, 833.3374966901328, 836.9707060252862, 850.2135524639807, 857.7426771968385, 4809.062232864558, 4857.174280372275, 4872.585830204402, 4885.145099607555, 5036.6726162799305, 866.6490071521044, 883.7348795361495, 884.8983663798479, 885.5660726244093, 890.875277461156, 3111.735502482269, 3136.9215769605944, 3140.9685903140676, 3177.952330715041, 3308.8180150091976, 9182.540872956326, 9481.09929556691, 9488.620455181854, 9497.762555936157, 9523.904760952302, 9472.621095156255, 9480.288978326653, 9481.09929556691, 9485.098915603006, 9488.383255088534, 7247.565146091251, 7309.232530584319, 7408.0, 7421.035265415461, 7512.812179695442, 4698.083873644743, 4741.193997020584, 4755.358026666356, 4776.785044672929, 4879.219324907997, 8635.0, 8644.740657780276, 8651.048384677806, 8657.61040753155, 8745.912540874764, 8339.291160251447, 8638.654453821777, 8670.739877803677, 8747.212750852454, 8888.155625215593, 6827.0, 6935.3411425914455, 6954.687039083152, 6955.965220173866, 6958.373746362851, 6533.281339052605, 6762.087118238975, 6992.545484543489, 7157.964210178916, 7236.493445458767, 6561.278259391559, 6584.736610535717, 6701.128853249046, 6739.123913891359, 6788.76239331621, 6549.181352330958, 6567.179102612174, 6592.077109204619, 6612.437942774918, 6632.933670663425, 5502.504774570308, 5504.532114770129, 5543.805966791304, 5584.664920104807, 5604.159376093618, 4486.349479325545, 4553.408056952575, 4606.239970404846, 4634.976825115853, 4699.624030077517, 4371.191329604034, 4414.0509790393935, 4530.501644819002, 4611.723296602215, 4681.110588988081], "value": 4101.582948972955, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.99050023749413, 388.0763782198367, 389.6707282346416, 393.5159753503189, 397.376119492397, 5178.5598224150945, 5264.289320941672, 5291.4708529147065, 5302.1781623848265, 5317.255584218213, 5133.1786914093755, 5146.639735218536, 5222.373315202181, 5264.710440925757, 5298.1522956327, 4158.0, 4169.6455801256925, 4170.4161417401665, 4177.561356057616, 4177.644900183488, 4151.8962025949295, 4198.622124008839, 4210.263203939298, 4210.663146948252, 4217.15656868626, 4000.2999475091888, 4133.834646614132, 4181.916361672765, 4188.267053265681, 4195.7063005589625, 4104.363823607346, 4107.856225032139, 4124.525679546846, 4133.979330103345, 4149.211649786531, 3715.5169827922196, 3717.5724791648768, 3723.087843478338, 3726.366517691999, 3728.8881333559766, 5177.275181474596, 5182.611304152204, 5182.818601348972, 5188.040212560688, 5211.609129315317, 4737.857128655808, 4795.760211989386, 4816.6764932196675, 4861.781219845116, 4903.8774030649165, 5553.16702494625, 5580.358258800249, 5595.580331475115, 5604.346717718283, 5642.802501912454, 4175.895602609929, 4195.958040419618, 4205.621494065549, 4217.641500472463, 4232.4286221360035, 5555.0, 5706.059939620507, 5719.256496655408, 5755.460391859018, 5805.82582522525, 4119.423280740699, 4122.711410201317, 4137.937930931039, 4169.749815011049, 4304.849330273488, 4339.522652508222, 4343.978280108579, 4346.913061738747, 4349.956500434955, 4379.321205213197, 4685.6017238535105, 4883.316335713003, 4941.975290123598, 4943.036107958914, 4956.801727930914, 5550.778828657689, 5655.802046928337, 5683.062294721325, 5685.129009849999, 5685.772569097273, 1482.4885414531968, 1507.3819733909045, 1510.4864346122372, 1511.2972467802524, 1513.341696362086, 996.8405055191134, 1050.3592808386877, 1057.1489950589848, 1070.6680928912062, 1087.8903518411175, 5240.266362709223, 5273.156294992783, 5273.630845840831, 5274.393444753902, 5287.836675931289, 5562.554995600342, 5577.6932268724795, 5590.2453168821685, 5637.703328234529, 5679.716014199356, 5108.259302401131, 5304.09052741013, 5333.466653334635, 5411.404745477994, 5441.755121019563, 2998.661044385383, 3001.5387382346266, 3009.5554134015797, 3015.0552893384083, 3024.349764800578, 5571.804986825442, 5874.471297583238, 5876.9118463223085, 5883.646981181143, 5911.0, 5743.483086522233, 5751.626144300607, 5754.223179870748, 5783.219265399117, 5799.884002320015, 5766.94233057664, 5787.71061446926, 5828.825135245949, 5838.941610583925, 5840.970795145997, 5241.842744717665, 5563.805266815642, 5576.804811831566, 5594.524465420485, 5635.577331700057, 5497.862553436196, 5529.479393166911, 5544.7227638617105, 5550.47270509301, 5573.606598350412, 5674.779922316722, 5801.941980580309, 5802.6808525531505, 5830.358660547421, 5849.766009359578, 3741.4948981887096, 4057.0, 4095.3584937714413, 4102.0, 4112.239235741397, 5626.774929002958, 5775.740091695968, 5787.305523337226, 5815.738291776795, 5817.2257461474655, 5507.284053073075, 5537.612367134262, 5548.722563871871, 5550.250716153268, 5584.855104703444, 5564.248826408383, 5574.777008919597, 5598.0, 5613.663180209201, 5621.409751976147, 5383.515483606494, 5533.861653458695, 5562.275694534545, 5581.581381396492, 5615.8876822464135, 5594.831850271574, 5606.047736345785, 5614.943850561442, 5618.803341883015, 5632.690202038927, 5439.211314359317, 5440.374356948843, 5440.782368705208, 5491.752871120889, 5508.237364043468], "value": 4299.773690819731, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.5580867766325, 744.7505085796258, 763.0919206144688, 812.2405550809993, 835.0730688935282, 14560.70878582429, 15891.761623575656, 15926.442574509894, 15927.44253951112, 15952.282147303385, 14827.184504852228, 14883.627909302273, 14901.105933643961, 14941.402343906231, 15044.548663540085, 764.571839769728, 775.2054144501889, 778.6885245901628, 781.6326326626477, 789.250212298316, 796.1560745609644, 798.3054742374113, 798.700487317256, 798.9201079891993, 799.0930294116191, 835.7492752174354, 836.9079401265856, 837.5100566168799, 837.660747397305, 837.949723016621, 832.6877420967137, 832.8584140696066, 833.2459124492344, 833.5748768128237, 833.6790335720791, 607.5200591532683, 609.0954931926086, 609.1411110334451, 609.8871708733855, 610.1793088296234, 15982.520524384287, 16029.679406411806, 16178.27197776103, 16228.0, 16522.669546609002, 13007.86992130076, 14334.354954027094, 14619.049761765451, 14845.554633361016, 14846.777298340534, 11660.125490588154, 11750.941245293805, 11841.230320029257, 12010.879891201064, 12097.334646594434, 3668.7248456365614, 3738.0, 3765.8870233893026, 3776.2636285924254, 3787.734858559902, 39619.80190099031, 47912.5208747915, 62132.0, 74448.0, 75986.0, 11206.159538034517, 11313.547458101748, 11339.262947908359, 11537.826932596017, 11834.289942603471, 19822.20711171566, 20709.275175368788, 20718.792812071988, 21697.13211471555, 21832.126714931226, 8370.748877533684, 8441.535715535581, 8482.109378515168, 8494.405391622651, 8559.74320770378, 4536.478304994967, 4537.38745269391, 4617.884552886205, 4619.976900115478, 4778.617710583145, 3439.1917899293644, 3490.1798077451776, 3512.1570823002808, 3530.135116896377, 3543.291341731664, 595.214317101422, 595.4670569840056, 610.9419605137505, 611.1932249430708, 621.9844503887349, 652.5138771615083, 680.1905732178692, 694.7255833945563, 704.3555147040503, 713.2368365848492, 10052.698419047294, 10256.641017564349, 10357.844632330522, 10473.319234249902, 10725.624603138853, 690.3441730356182, 718.2960698515379, 748.842743023971, 750.2984709296825, 754.705664790732, 4643.419572553431, 4969.130402179604, 5062.0, 5075.3402057733, 5078.0, 70746.64626676834, 73646.63176684083, 74639.0, 74775.25224747791, 76349.6182519095, 43527.0, 55381.72309138429, 63249.36750632527, 63309.366906330346, 64023.359766402675, 47343.5265647346, 47586.28620570694, 48264.0, 51626.74186629043, 58678.413215867295, 10715.839262411226, 11094.223404361617, 11139.665810025712, 11180.944095279472, 11408.714782130513, 37787.62212377951, 38051.42922856104, 38252.234955300184, 38719.225615488096, 39106.80446597749, 45333.32000019936, 45447.545524545654, 46818.53181468142, 48925.0, 51508.0, 13466.124701894345, 13552.864471355555, 13579.388927498483, 13890.444382224598, 14167.149971001772, 14961.700765984837, 15083.698326033636, 15362.846371536589, 15421.537353879401, 15881.52355429339, 13762.311884405539, 14213.928930355281, 14232.217228052557, 14376.712465750836, 14962.775558366415, 14003.0, 14524.927375363055, 14533.781993269899, 14541.709165816836, 14653.413863445343, 13713.588592342247, 13845.169289842239, 14156.0, 14731.410743570137, 14756.63108422298, 10865.782684346426, 11196.608118715805, 11201.133483666248, 11493.252938558968, 11523.769524609628, 9734.172595329477, 9774.462404567532, 10773.19201059908, 10938.179636527451, 11041.889581104086], "value": 7123.7987823472195, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 112.0, 122.0, 113.0, 111.0, 112.0, 110.0, 110.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 121.0, 126.0, 161.0, 121.0, 123.0, 121.0, 121.0, 155.0, 123.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 161.0, 162.0, 161.0, 163.0, 163.0, 163.0, 163.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 77.0, 111.0, 77.0, 75.0, 77.0, 76.0, 102.0, 77.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 114.0, 84.0, 83.0, 83.0, 100.0, 85.0, 84.0, 109.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 148.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 53.0, 54.0, 55.0, 55.0, 54.0, 54.0, 56.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 84.0, 84.0, 83.0, 84.0, 92.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 139.0, 139.0, 133.0, 131.0, 131.0, 132.0, 138.0, 171.0, 131.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 173.0, 179.0, 171.0, 173.0, 175.0, 175.0, 172.0, 173.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 67.0, 64.0, 67.0, 63.0, 64.0, 65.0, 64.0, 63.0, 62.0], "value": 65.2, "unit": "score"}]}]} 08:31:29 INFO - Return code: 0 08:31:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:31:29 ERROR - # TBPL SUCCESS # 08:31:29 INFO - Running post-action listener: _resource_record_post_action 08:31:29 INFO - [mozharness: 2017-02-17 16:31:29.517933Z] Finished run-tests step (success) 08:31:29 INFO - Running post-run listener: _resource_record_post_run 08:31:29 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 365129728; Read time: 0; Write time: 247580 08:31:29 INFO - TinderboxPrint: CPU usage
13.1% 08:31:29 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 08:31:29 INFO - TinderboxPrint: I/O write bytes / time
365,129,728 / 247,580 08:31:29 INFO - TinderboxPrint: CPU idle
8,292.8 (86.9%) 08:31:29 INFO - TinderboxPrint: CPU user
1,207.0 (12.6%) 08:31:29 INFO - TinderboxPrint: Swap in / out
0 / 0 08:31:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110833664; Read time: 0; Write time: 114804 08:31:29 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 254296064; Read time: 0; Write time: 132776 08:31:30 INFO - Running post-run listener: _upload_blobber_files 08:31:30 INFO - Blob upload gear active. 08:31:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:30 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 08:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:31:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:31:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:31:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:31 INFO - (blobuploader) - INFO - Done attempting. 08:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:34 INFO - (blobuploader) - INFO - Done attempting. 08:31:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:36 INFO - (blobuploader) - INFO - Done attempting. 08:31:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:36 INFO - (blobuploader) - INFO - Done attempting. 08:31:36 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:36 INFO - Return code: 0 08:31:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b8fdeb7794977810b4b34e251a855c0d8f279c7a1f7f2f91bd79f8238c76c3e1f7d9071a34bf24c2be4ca48f7fbc74353e8dbb39039c82b258f11bc600eb23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e5f2b397dd34cc53e2071bf612f9606df6657c84c48d0dd5712ae161c058affd5f67926f2a9e7bd8dc60d5fdcc9f6c2baf06ad2c53e63d7f8b39dd183fa29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0587f4fb46ad214dea763c3a3848367a779187d9bd9c3cb310cf959d38fdd7ecf37cc173622094399ecc91dc415dc4aa3679085034200d361acafb7b9d367fa9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a3b4fc2475be5bf5c92e6e7b749f7ad1f65c8a8f6c097e7357884fde6945d1dff1653cc9f139fa1f525d448a52579c34910005db4e2c68766e5caedf610ecd0"} 08:31:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:36 INFO - Contents: 08:31:36 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b8fdeb7794977810b4b34e251a855c0d8f279c7a1f7f2f91bd79f8238c76c3e1f7d9071a34bf24c2be4ca48f7fbc74353e8dbb39039c82b258f11bc600eb23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e5f2b397dd34cc53e2071bf612f9606df6657c84c48d0dd5712ae161c058affd5f67926f2a9e7bd8dc60d5fdcc9f6c2baf06ad2c53e63d7f8b39dd183fa29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0587f4fb46ad214dea763c3a3848367a779187d9bd9c3cb310cf959d38fdd7ecf37cc173622094399ecc91dc415dc4aa3679085034200d361acafb7b9d367fa9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a3b4fc2475be5bf5c92e6e7b749f7ad1f65c8a8f6c097e7357884fde6945d1dff1653cc9f139fa1f525d448a52579c34910005db4e2c68766e5caedf610ecd0"} 08:31:36 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:36 INFO - Copying logs to upload dir... 08:31:36 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.177579 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 08:31:36.813083) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:31:36.816739) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 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/sha512/61b8fdeb7794977810b4b34e251a855c0d8f279c7a1f7f2f91bd79f8238c76c3e1f7d9071a34bf24c2be4ca48f7fbc74353e8dbb39039c82b258f11bc600eb23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e5f2b397dd34cc53e2071bf612f9606df6657c84c48d0dd5712ae161c058affd5f67926f2a9e7bd8dc60d5fdcc9f6c2baf06ad2c53e63d7f8b39dd183fa29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0587f4fb46ad214dea763c3a3848367a779187d9bd9c3cb310cf959d38fdd7ecf37cc173622094399ecc91dc415dc4aa3679085034200d361acafb7b9d367fa9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a3b4fc2475be5bf5c92e6e7b749f7ad1f65c8a8f6c097e7357884fde6945d1dff1653cc9f139fa1f525d448a52579c34910005db4e2c68766e5caedf610ecd0"} build_url:https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/R4PvaOweSLmak8_VvaO9vg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b8fdeb7794977810b4b34e251a855c0d8f279c7a1f7f2f91bd79f8238c76c3e1f7d9071a34bf24c2be4ca48f7fbc74353e8dbb39039c82b258f11bc600eb23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6e5f2b397dd34cc53e2071bf612f9606df6657c84c48d0dd5712ae161c058affd5f67926f2a9e7bd8dc60d5fdcc9f6c2baf06ad2c53e63d7f8b39dd183fa29e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0587f4fb46ad214dea763c3a3848367a779187d9bd9c3cb310cf959d38fdd7ecf37cc173622094399ecc91dc415dc4aa3679085034200d361acafb7b9d367fa9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a3b4fc2475be5bf5c92e6e7b749f7ad1f65c8a8f6c097e7357884fde6945d1dff1653cc9f139fa1f525d448a52579c34910005db4e2c68766e5caedf610ecd0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 08:31:36.844077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:31:36.844822) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1487347840.778265-2067071500 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004697 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 08:31:36.927361) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:31:36.928052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 08:31:36.928692) ========= ========= Total master_lag: 0.29 =========